builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0309 starttime: 1480571289.64 results: success (0) buildid: 20161130192045 builduid: 412b53c73f8b4251b536fb41cc135b7f revision: cd4cdcc9ad6c45dad8b8d8c0d40e459db2bca8a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 21:48:09.641675) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 21:48:09.642122) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 21:48:09.642412) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 21:48:09.674432) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 21:48:09.674813) ========= 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.uq8EnFstTC/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.RiNayVu7w0/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-30 21:48:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 61.5M=0s 2016-11-30 21:48:09 (61.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135630 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 21:48:09.837150) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 21:48:09.840490) ========= 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.uq8EnFstTC/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.RiNayVu7w0/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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.024880 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 21:48:09.889472) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-30 21:48:09.889821) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cd4cdcc9ad6c45dad8b8d8c0d40e459db2bca8a1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev cd4cdcc9ad6c45dad8b8d8c0d40e459db2bca8a1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uq8EnFstTC/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.RiNayVu7w0/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-30 21:48:09,990 truncating revision to first 12 chars 2016-11-30 21:48:09,990 Setting DEBUG logging. 2016-11-30 21:48:09,990 attempt 1/10 2016-11-30 21:48:09,990 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cd4cdcc9ad6c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-30 21:48:10,772 unpacking tar archive at: mozilla-central-cd4cdcc9ad6c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.048284 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-30 21:48:10.966641) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 21:48:10.967012) ========= script_repo_revision: cd4cdcc9ad6c45dad8b8d8c0d40e459db2bca8a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 21:48:10.970448) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 21:48:10.970719) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 21:48:10.993344) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-30 21:48:10.993784) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uq8EnFstTC/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.RiNayVu7w0/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 21:48:11 INFO - MultiFileLogger online at 20161130 21:48:11 in /builds/slave/test 21:48:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:48:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:48:11 INFO - {'append_to_log': False, 21:48:11 INFO - 'base_work_dir': '/builds/slave/test', 21:48:11 INFO - 'blob_upload_branch': 'Firefox', 21:48:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:48:11 INFO - 'branch': 'Firefox', 21:48:11 INFO - 'buildbot_json_path': 'buildprops.json', 21:48:11 INFO - 'config_files': ('talos/mac_config.py',), 21:48:11 INFO - 'default_actions': ('clobber', 21:48:11 INFO - 'read-buildbot-config', 21:48:11 INFO - 'download-and-extract', 21:48:11 INFO - 'populate-webroot', 21:48:11 INFO - 'create-virtualenv', 21:48:11 INFO - 'install', 21:48:11 INFO - 'run-tests'), 21:48:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:48:11 INFO - 'download_minidump_stackwalk': True, 21:48:11 INFO - 'download_symbols': 'ondemand', 21:48:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:48:11 INFO - 'tooltool.py': '/tools/tooltool.py', 21:48:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:48:11 INFO - '/tools/misc-python/virtualenv.py')}, 21:48:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:48:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:48:11 INFO - 'installer_path': 'installer.exe', 21:48:11 INFO - 'log_level': 'info', 21:48:11 INFO - 'log_name': 'talos', 21:48:11 INFO - 'log_to_console': True, 21:48:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:48:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:48:11 INFO - 'opt_config_files': (), 21:48:11 INFO - 'pip_index': False, 21:48:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:48:11 INFO - 'cmd': ('bash', 21:48:11 INFO - '-c', 21:48:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:48:11 INFO - 'enabled': True, 21:48:11 INFO - 'halt_on_failure': False, 21:48:11 INFO - 'name': 'check_screen_resolution'},), 21:48:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:48:11 INFO - 'cmd': ('bash', 21:48:11 INFO - '-c', 21:48:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:48:11 INFO - 'enabled': True, 21:48:11 INFO - 'halt_on_failure': False, 21:48:11 INFO - 'name': 'check_screen_resolution'},), 21:48:11 INFO - 'run_cmd_checks_enabled': True, 21:48:11 INFO - 'sps_profile': False, 21:48:11 INFO - 'sps_profile_interval': 0, 21:48:11 INFO - 'suite': 'dromaeojs-e10s', 21:48:11 INFO - 'system_bits': '32', 21:48:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:48:11 INFO - 'title': 't-yosemite-r7-0309', 21:48:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:48:11 INFO - 'use_talos_json': True, 21:48:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:48:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:48:11 INFO - 'work_dir': 'build'} 21:48:11 INFO - [mozharness: 2016-12-01 05:48:11.202917Z] Running clobber step. 21:48:11 INFO - Running pre-action listener: _resource_record_pre_action 21:48:11 INFO - Running main action method: clobber 21:48:11 INFO - rmtree: /builds/slave/test/build 21:48:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:48:13 INFO - Running post-action listener: _resource_record_post_action 21:48:13 INFO - [mozharness: 2016-12-01 05:48:13.578886Z] Finished clobber step (success) 21:48:13 INFO - [mozharness: 2016-12-01 05:48:13.579095Z] Running read-buildbot-config step. 21:48:13 INFO - Running pre-action listener: _resource_record_pre_action 21:48:13 INFO - Running main action method: read_buildbot_config 21:48:13 INFO - Using buildbot properties: 21:48:13 INFO - { 21:48:13 INFO - "project": "", 21:48:13 INFO - "product": "firefox", 21:48:13 INFO - "script_repo_revision": "production", 21:48:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 21:48:13 INFO - "repository": "", 21:48:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 21:48:13 INFO - "buildid": "20161130192045", 21:48:13 INFO - "pgo_build": "False", 21:48:13 INFO - "basedir": "/builds/slave/test", 21:48:13 INFO - "buildnumber": 187, 21:48:13 INFO - "slavename": "t-yosemite-r7-0309", 21:48:13 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 21:48:13 INFO - "platform": "yosemite_r7", 21:48:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:48:13 INFO - "branch": "mozilla-central", 21:48:13 INFO - "repo_path": "mozilla-central", 21:48:13 INFO - "revision": "cd4cdcc9ad6c45dad8b8d8c0d40e459db2bca8a1", 21:48:13 INFO - "stage_platform": "macosx64", 21:48:13 INFO - "builduid": "412b53c73f8b4251b536fb41cc135b7f", 21:48:13 INFO - "slavebuilddir": "test" 21:48:13 INFO - } 21:48:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 21:48:13 INFO - Running post-action listener: _resource_record_post_action 21:48:13 INFO - [mozharness: 2016-12-01 05:48:13.584737Z] Finished read-buildbot-config step (success) 21:48:13 INFO - [mozharness: 2016-12-01 05:48:13.584941Z] Running download-and-extract step. 21:48:13 INFO - Running pre-action listener: _resource_record_pre_action 21:48:13 INFO - Running main action method: download_and_extract 21:48:13 INFO - mkdir: /builds/slave/test/build/tests 21:48:13 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')]} 21:48:13 INFO - trying https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 21:48:13 INFO - Downloading https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:48:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 21:48:14 INFO - Downloaded 1199 bytes. 21:48:14 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:48:14 INFO - Using the following test package requirements: 21:48:14 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:48:14 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:48:14 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 21:48:14 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:48:14 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 21:48:14 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:48:14 INFO - u'jsshell-mac.zip'], 21:48:14 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:48:14 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 21:48:14 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:48:14 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:48:14 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 21:48:14 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:48:14 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 21:48:14 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:48:14 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 21:48:14 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:48:14 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 21:48:14 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:48:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:48:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:48:14 INFO - Fetch https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:48:15 INFO - Expected file size: 16275560 21:48:15 INFO - Obtained file size: 16275560 21:48:21 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:48:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:48:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:48:21 INFO - Fetch https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:48:22 INFO - Expected file size: 16275560 21:48:22 INFO - Obtained file size: 16275560 21:48:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 21:48:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:48:29 INFO - Fetch https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 21:48:30 INFO - Expected file size: 14409702 21:48:30 INFO - Obtained file size: 14409702 21:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:30 INFO - trying https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:48:30 INFO - Downloading https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 21:48:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 21:48:33 INFO - Downloaded 107611132 bytes. 21:48:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:48:33 INFO - mkdir: /builds/slave/test/properties 21:48:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:48:33 INFO - Writing to file /builds/slave/test/properties/build_url 21:48:33 INFO - Contents: 21:48:33 INFO - build_url:https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:48:34 INFO - Running post-action listener: _resource_record_post_action 21:48:34 INFO - Running post-action listener: set_extra_try_arguments 21:48:34 INFO - [mozharness: 2016-12-01 05:48:34.188885Z] Finished download-and-extract step (success) 21:48:34 INFO - [mozharness: 2016-12-01 05:48:34.189040Z] Running populate-webroot step. 21:48:34 INFO - Running pre-action listener: _resource_record_pre_action 21:48:34 INFO - Running main action method: populate_webroot 21:48:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:48:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:48:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:48:34 INFO - '--tppagecycles', 21:48:34 INFO - '7'], 21:48:34 INFO - 'tests': ['tsvgm']}}, 21:48:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:48:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:48:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:48:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:48:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:48:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:48:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:48:34 INFO - 'talos_options': ['--disable-e10s'], 21:48:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:48:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:48:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:48:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:48:34 INFO - 'talos_options': ['--disable-e10s'], 21:48:34 INFO - 'tests': ['damp', 'tps']}, 21:48:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:48:34 INFO - 'tests': ['damp', 'tps']}, 21:48:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:48:34 INFO - 'tests': ['dromaeo_dom']}, 21:48:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:48:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:48:34 INFO - 'tests': ['basic_compositor_video']}, 21:48:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:48:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:48:34 INFO - 'tests': ['a11yr', 21:48:34 INFO - 'ts_paint', 21:48:34 INFO - 'tpaint', 21:48:34 INFO - 'sessionrestore', 21:48:34 INFO - 'sessionrestore_no_auto_restore', 21:48:34 INFO - 'tabpaint']}, 21:48:34 INFO - 'other-e10s': {'tests': ['a11yr', 21:48:34 INFO - 'ts_paint', 21:48:34 INFO - 'tpaint', 21:48:34 INFO - 'sessionrestore', 21:48:34 INFO - 'sessionrestore_no_auto_restore', 21:48:34 INFO - 'tabpaint']}, 21:48:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:48:34 INFO - 'ts_paint', 21:48:34 INFO - 'tpaint', 21:48:34 INFO - 'sessionrestore', 21:48:34 INFO - 'sessionrestore_no_auto_restore', 21:48:34 INFO - 'tabpaint']}, 21:48:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:48:34 INFO - 'ts_paint', 21:48:34 INFO - 'tpaint', 21:48:34 INFO - 'sessionrestore', 21:48:34 INFO - 'sessionrestore_no_auto_restore', 21:48:34 INFO - 'tabpaint']}, 21:48:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:48:34 INFO - 'tests': ['a11yr', 21:48:34 INFO - 'ts_paint', 21:48:34 INFO - 'tpaint', 21:48:34 INFO - 'sessionrestore', 21:48:34 INFO - 'sessionrestore_no_auto_restore', 21:48:34 INFO - 'tabpaint']}, 21:48:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:48:34 INFO - 'tests': ['a11yr', 21:48:34 INFO - 'ts_paint', 21:48:34 INFO - 'tpaint', 21:48:34 INFO - 'sessionrestore', 21:48:34 INFO - 'sessionrestore_no_auto_restore', 21:48:34 INFO - 'tabpaint']}, 21:48:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:48:34 INFO - 'tests': ['tsvgx', 21:48:34 INFO - 'tsvgr_opacity', 21:48:34 INFO - 'tart', 21:48:34 INFO - 'tscrollx', 21:48:34 INFO - 'cart', 21:48:34 INFO - 'tsvg_static']}, 21:48:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:48:34 INFO - 'tsvgr_opacity', 21:48:34 INFO - 'tart', 21:48:34 INFO - 'tscrollx', 21:48:34 INFO - 'cart', 21:48:34 INFO - 'tsvg_static']}, 21:48:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:48:34 INFO - 'talos_options': ['--disable-e10s'], 21:48:34 INFO - 'tests': ['tp5o']}, 21:48:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:48:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:48:34 INFO - 'talos_options': ['--disable-e10s', 21:48:34 INFO - '--xperf_path', 21:48:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:48:34 INFO - 'tests': ['tp5n']}, 21:48:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:48:34 INFO - 'talos_options': ['--xperf_path', 21:48:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:48:34 INFO - 'tests': ['tp5n']}}, 21:48:34 INFO - 'talos.zip': {'path': '', 21:48:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:48:34 INFO - Running post-action listener: _resource_record_post_action 21:48:34 INFO - [mozharness: 2016-12-01 05:48:34.207618Z] Finished populate-webroot step (success) 21:48:34 INFO - [mozharness: 2016-12-01 05:48:34.207733Z] Running create-virtualenv step. 21:48:34 INFO - Running pre-action listener: _resource_record_pre_action 21:48:34 INFO - Running main action method: create_virtualenv 21:48:34 INFO - Creating virtualenv /builds/slave/test/build/venv 21:48:34 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 21:48:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:48:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:48:34 INFO - Using real prefix '/tools/python27' 21:48:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:48:35 INFO - Installing distribute.............................................................................................................................................................................................done. 21:48:37 INFO - Installing pip.................done. 21:48:37 INFO - Return code: 0 21:48:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:48:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:48:38 INFO - Reading from file tmpfile_stdout 21:48:38 INFO - Output received: 21:48:38 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) 21:48:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:38 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 0x10fb583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10face570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98257e7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f96ed30>, '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 0x10f7d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f966b20>, '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.uq8EnFstTC/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.RiNayVu7w0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 21:48:38 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 21:48:38 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 21:48:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uq8EnFstTC/Render', 21:48:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:38 INFO - 'HOME': '/Users/cltbld', 21:48:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:38 INFO - 'LOGNAME': 'cltbld', 21:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:38 INFO - 'MOZ_NO_REMOTE': '1', 21:48:38 INFO - 'NO_EM_RESTART': '1', 21:48:38 INFO - 'PAGER': '/bin/cat', 21:48:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:38 INFO - 'PWD': '/builds/slave/test', 21:48:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:38 INFO - 'SHELL': '/bin/bash', 21:48:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RiNayVu7w0/Listeners', 21:48:38 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 21:48:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:38 INFO - 'USER': 'cltbld', 21:48:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:38 INFO - 'XPC_FLAGS': '0x0', 21:48:38 INFO - 'XPC_SERVICE_NAME': '0', 21:48:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:38 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 21:48:38 INFO - Cleaning up... 21:48:38 INFO - Return code: 0 21:48:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:38 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 0x10fb583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10face570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98257e7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f96ed30>, '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 0x10f7d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f966b20>, '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.uq8EnFstTC/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.RiNayVu7w0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 21:48:38 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 21:48:38 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 21:48:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uq8EnFstTC/Render', 21:48:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:38 INFO - 'HOME': '/Users/cltbld', 21:48:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:38 INFO - 'LOGNAME': 'cltbld', 21:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:38 INFO - 'MOZ_NO_REMOTE': '1', 21:48:38 INFO - 'NO_EM_RESTART': '1', 21:48:38 INFO - 'PAGER': '/bin/cat', 21:48:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:38 INFO - 'PWD': '/builds/slave/test', 21:48:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:38 INFO - 'SHELL': '/bin/bash', 21:48:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RiNayVu7w0/Listeners', 21:48:38 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 21:48:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:38 INFO - 'USER': 'cltbld', 21:48:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:38 INFO - 'XPC_FLAGS': '0x0', 21:48:38 INFO - 'XPC_SERVICE_NAME': '0', 21:48:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:38 INFO - Downloading/unpacking psutil>=3.1.1 21:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:38 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 21:48:38 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 21:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:48:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:48:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:48:42 INFO - Installing collected packages: psutil 21:48:42 INFO - Running setup.py install for psutil 21:48:42 INFO - building 'psutil._psutil_osx' extension 21:48:42 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 21:48:42 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 21:48:42 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 21:48:42 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 21:48:43 INFO - building 'psutil._psutil_posix' extension 21:48:43 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 21:48:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:48:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:48:43 INFO - ^ 21:48:43 INFO - 1 warning generated. 21:48:43 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 21:48:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:48:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:48:43 INFO - Successfully installed psutil 21:48:43 INFO - Cleaning up... 21:48:43 INFO - Return code: 0 21:48:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:48: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')]} 21:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48: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')]} 21:48:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10face570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98257e7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f96ed30>, '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 0x10f7d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f966b20>, '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.uq8EnFstTC/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.RiNayVu7w0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 21:48: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:48: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 mozsystemmonitor==0.3 21:48:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uq8EnFstTC/Render', 21:48:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:43 INFO - 'HOME': '/Users/cltbld', 21:48:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:43 INFO - 'LOGNAME': 'cltbld', 21:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:43 INFO - 'MOZ_NO_REMOTE': '1', 21:48:43 INFO - 'NO_EM_RESTART': '1', 21:48:43 INFO - 'PAGER': '/bin/cat', 21:48:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:43 INFO - 'PWD': '/builds/slave/test', 21:48:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:43 INFO - 'SHELL': '/bin/bash', 21:48:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RiNayVu7w0/Listeners', 21:48:43 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 21:48:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:43 INFO - 'USER': 'cltbld', 21:48:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:43 INFO - 'XPC_FLAGS': '0x0', 21:48:43 INFO - 'XPC_SERVICE_NAME': '0', 21:48:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:48:43 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 21:48:43 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 21:48:43 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 21:48:43 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 21:48:43 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 21:48:43 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 21:48:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:48:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:48:46 INFO - Installing collected packages: mozsystemmonitor 21:48:46 INFO - Running setup.py install for mozsystemmonitor 21:48:46 INFO - Successfully installed mozsystemmonitor 21:48:46 INFO - Cleaning up... 21:48:46 INFO - Return code: 0 21:48:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:46 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 0x10fb583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10face570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98257e7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f96ed30>, '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 0x10f7d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f966b20>, '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.uq8EnFstTC/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.RiNayVu7w0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 21:48:46 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 21:48:46 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 21:48:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uq8EnFstTC/Render', 21:48:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:46 INFO - 'HOME': '/Users/cltbld', 21:48:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:46 INFO - 'LOGNAME': 'cltbld', 21:48:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:46 INFO - 'MOZ_NO_REMOTE': '1', 21:48:46 INFO - 'NO_EM_RESTART': '1', 21:48:46 INFO - 'PAGER': '/bin/cat', 21:48:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:46 INFO - 'PWD': '/builds/slave/test', 21:48:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:46 INFO - 'SHELL': '/bin/bash', 21:48:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RiNayVu7w0/Listeners', 21:48:46 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 21:48:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:46 INFO - 'USER': 'cltbld', 21:48:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:46 INFO - 'XPC_FLAGS': '0x0', 21:48:46 INFO - 'XPC_SERVICE_NAME': '0', 21:48:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:46 INFO - Downloading/unpacking jsonschema==2.5.1 21:48:46 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 21:48:46 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 21:48:46 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 21:48:46 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 21:48:46 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 21:48:46 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 21:48:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:48:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:48:49 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 21:48:49 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 21:48:49 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 21:48:49 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 21:48:49 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 21:48:49 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 21:48:49 INFO - Downloading functools32-3.2.3-2.tar.gz 21:48:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:48:50 INFO - warning: no files found matching '*.txt' 21:48:50 INFO - no previously-included directories found matching 'build' 21:48:50 INFO - no previously-included directories found matching 'dist' 21:48:50 INFO - no previously-included directories found matching '.git*' 21:48:50 INFO - Installing collected packages: jsonschema, functools32 21:48:50 INFO - Running setup.py install for functools32 21:48:50 INFO - warning: no files found matching '*.txt' 21:48:50 INFO - no previously-included directories found matching 'build' 21:48:50 INFO - no previously-included directories found matching 'dist' 21:48:50 INFO - no previously-included directories found matching '.git*' 21:48:50 INFO - Successfully installed jsonschema functools32 21:48:50 INFO - Cleaning up... 21:48:50 INFO - Return code: 0 21:48:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:50 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 0x10fb583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10face570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98257e7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f96ed30>, '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 0x10f7d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f966b20>, '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.uq8EnFstTC/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.RiNayVu7w0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 21:48:50 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 21:48:50 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 21:48:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uq8EnFstTC/Render', 21:48:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:50 INFO - 'HOME': '/Users/cltbld', 21:48:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:50 INFO - 'LOGNAME': 'cltbld', 21:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:50 INFO - 'MOZ_NO_REMOTE': '1', 21:48:50 INFO - 'NO_EM_RESTART': '1', 21:48:50 INFO - 'PAGER': '/bin/cat', 21:48:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:50 INFO - 'PWD': '/builds/slave/test', 21:48:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:50 INFO - 'SHELL': '/bin/bash', 21:48:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RiNayVu7w0/Listeners', 21:48:50 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 21:48:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:50 INFO - 'USER': 'cltbld', 21:48:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:50 INFO - 'XPC_FLAGS': '0x0', 21:48:50 INFO - 'XPC_SERVICE_NAME': '0', 21:48:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:48:50 INFO - Cleaning up... 21:48:50 INFO - Return code: 0 21:48:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:50 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 0x10fb583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10face570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98257e7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f96ed30>, '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 0x10f7d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f966b20>, '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.uq8EnFstTC/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.RiNayVu7w0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 21:48:50 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 21:48:50 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 21:48:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uq8EnFstTC/Render', 21:48:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:50 INFO - 'HOME': '/Users/cltbld', 21:48:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:50 INFO - 'LOGNAME': 'cltbld', 21:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:50 INFO - 'MOZ_NO_REMOTE': '1', 21:48:50 INFO - 'NO_EM_RESTART': '1', 21:48:50 INFO - 'PAGER': '/bin/cat', 21:48:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:50 INFO - 'PWD': '/builds/slave/test', 21:48:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:50 INFO - 'SHELL': '/bin/bash', 21:48:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RiNayVu7w0/Listeners', 21:48:50 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 21:48:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:50 INFO - 'USER': 'cltbld', 21:48:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:50 INFO - 'XPC_FLAGS': '0x0', 21:48:50 INFO - 'XPC_SERVICE_NAME': '0', 21:48:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:50 INFO - Downloading/unpacking blobuploader==1.2.4 21:48:50 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 21:48:50 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 21:48:50 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 21:48:50 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 21:48:50 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 21:48:50 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 21:48:53 INFO - Downloading blobuploader-1.2.4.tar.gz 21:48:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:48:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:48:53 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 21:48:53 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 21:48:53 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 21:48:53 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 21:48:53 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 21:48:53 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 21:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:48:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:54 INFO - Downloading docopt-0.6.1.tar.gz 21:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:48:54 INFO - Installing collected packages: blobuploader, requests, docopt 21:48:54 INFO - Running setup.py install for blobuploader 21:48:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:48:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:48:55 INFO - Running setup.py install for requests 21:48:55 INFO - Running setup.py install for docopt 21:48:55 INFO - Successfully installed blobuploader requests docopt 21:48:55 INFO - Cleaning up... 21:48:55 INFO - Return code: 0 21:48:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:55 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')]} 21:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:55 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')]} 21:48:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:55 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 0x10fb583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10face570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98257e7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f96ed30>, '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 0x10f7d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f966b20>, '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.uq8EnFstTC/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.RiNayVu7w0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 21:48:55 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 21:48:55 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 21:48:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uq8EnFstTC/Render', 21:48:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:55 INFO - 'HOME': '/Users/cltbld', 21:48:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:55 INFO - 'LOGNAME': 'cltbld', 21:48:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:55 INFO - 'MOZ_NO_REMOTE': '1', 21:48:55 INFO - 'NO_EM_RESTART': '1', 21:48:55 INFO - 'PAGER': '/bin/cat', 21:48:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:55 INFO - 'PWD': '/builds/slave/test', 21:48:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:55 INFO - 'SHELL': '/bin/bash', 21:48:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RiNayVu7w0/Listeners', 21:48:55 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 21:48:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:55 INFO - 'USER': 'cltbld', 21:48:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:55 INFO - 'XPC_FLAGS': '0x0', 21:48:55 INFO - 'XPC_SERVICE_NAME': '0', 21:48:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:48:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-8LGrAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:48:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-E_4gPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:48:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-KFozfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:48:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-TW7w75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:48:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-XSUJGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:48:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-awGyMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:48:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-4rr1BA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:48:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-lh17gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:48:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-48o9fA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:48:56 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-67O95m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:48:57 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-eN5mWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:48:57 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-5sUM4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:48:57 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-47raac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:48:57 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-EC978g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:57 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-k4FbMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:48:57 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-7I8ruM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:48:57 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-SnoQy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:48:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:48:57 INFO - Running setup.py install for manifestparser 21:48:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:48:57 INFO - Running setup.py install for mozcrash 21:48:58 INFO - Running setup.py install for mozdebug 21:48:58 INFO - Running setup.py install for mozdevice 21:48:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:48:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:48:58 INFO - Running setup.py install for mozfile 21:48:58 INFO - Running setup.py install for mozhttpd 21:48:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:48:58 INFO - Running setup.py install for mozinfo 21:48:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:48:58 INFO - Running setup.py install for mozInstall 21:48:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:48:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:48:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:48:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:48:58 INFO - Running setup.py install for mozleak 21:48:59 INFO - Running setup.py install for mozlog 21:48:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:48:59 INFO - Running setup.py install for moznetwork 21:48:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:48:59 INFO - Running setup.py install for mozprocess 21:48:59 INFO - Running setup.py install for mozprofile 21:48:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:48:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:48:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:48:59 INFO - Running setup.py install for mozrunner 21:48:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:48:59 INFO - Running setup.py install for mozscreenshot 21:49:00 INFO - Running setup.py install for moztest 21:49:00 INFO - Running setup.py install for mozversion 21:49:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:49:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:49:00 INFO - Cleaning up... 21:49:00 INFO - Return code: 0 21:49:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:00 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 0x10fb583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10face570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98257e7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f96ed30>, '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 0x10f7d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f966b20>, '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.uq8EnFstTC/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.RiNayVu7w0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 21:49:00 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 21:49:00 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 21:49:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uq8EnFstTC/Render', 21:49:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:00 INFO - 'HOME': '/Users/cltbld', 21:49:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:00 INFO - 'LOGNAME': 'cltbld', 21:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:00 INFO - 'MOZ_NO_REMOTE': '1', 21:49:00 INFO - 'NO_EM_RESTART': '1', 21:49:00 INFO - 'PAGER': '/bin/cat', 21:49:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:00 INFO - 'PWD': '/builds/slave/test', 21:49:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:00 INFO - 'SHELL': '/bin/bash', 21:49:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RiNayVu7w0/Listeners', 21:49:00 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 21:49:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:00 INFO - 'USER': 'cltbld', 21:49:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:00 INFO - 'XPC_FLAGS': '0x0', 21:49:00 INFO - 'XPC_SERVICE_NAME': '0', 21:49:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:49:00 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-mAqj9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:49:00 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)) 21:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:49:00 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-lEepiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:49:00 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)) 21:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:49:00 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-kWVqns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:49:00 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)) 21:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:49:00 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-dfdlFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:49:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-fJPbg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:49:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-WE75zk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:49:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-UB6Rgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:49:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-pVP6yC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:49:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-G0793a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:49:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-FwI9KF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:49:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-lb0Sb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:49:01 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)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:49:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-zksv2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:49:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-mlkSEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:49:02 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)) 21:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:49:02 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-6tRUMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:49:02 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)) 21:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:49:02 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-54Xjks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:49:02 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)) 21:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:49:02 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-vwYqfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:49:02 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-z3pm8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:49:02 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)) 21:49:02 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)) 21:49:02 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)) 21:49:02 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)) 21:49:02 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)) 21:49:02 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)) 21:49:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:49:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:49:05 INFO - Downloading blessings-1.6.tar.gz 21:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:49:05 INFO - Installing collected packages: blessings 21:49:05 INFO - Running setup.py install for blessings 21:49:05 INFO - Successfully installed blessings 21:49:05 INFO - Cleaning up... 21:49:05 INFO - Return code: 0 21:49:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:49:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:49:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:49:05 INFO - Reading from file tmpfile_stdout 21:49:05 INFO - Current package versions: 21:49:05 INFO - blessings == 1.6 21:49:05 INFO - blobuploader == 1.2.4 21:49:05 INFO - docopt == 0.6.1 21:49:05 INFO - functools32 == 3.2.3-2 21:49:05 INFO - jsonschema == 2.5.1 21:49:05 INFO - manifestparser == 1.1 21:49:05 INFO - mozInstall == 1.12 21:49:05 INFO - mozcrash == 1.0 21:49:05 INFO - mozdebug == 0.1 21:49:05 INFO - mozdevice == 0.49 21:49:05 INFO - mozfile == 1.2 21:49:05 INFO - mozhttpd == 0.7 21:49:05 INFO - mozinfo == 0.9 21:49:05 INFO - mozleak == 0.1 21:49:05 INFO - mozlog == 3.4 21:49:05 INFO - moznetwork == 0.27 21:49:05 INFO - mozprocess == 0.23 21:49:05 INFO - mozprofile == 0.28 21:49:05 INFO - mozrunner == 6.13 21:49:05 INFO - mozscreenshot == 0.1 21:49:05 INFO - mozsystemmonitor == 0.3 21:49:05 INFO - moztest == 0.7 21:49:05 INFO - mozversion == 1.4 21:49:05 INFO - psutil == 3.1.1 21:49:05 INFO - requests == 1.2.3 21:49:05 INFO - wsgiref == 0.1.2 21:49:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:06 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 0x10fb583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10face570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98257e7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f96ed30>, '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 0x10f7d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f966b20>, '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.uq8EnFstTC/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.RiNayVu7w0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 21:49:06 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 21:49:06 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 21:49:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uq8EnFstTC/Render', 21:49:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:06 INFO - 'HOME': '/Users/cltbld', 21:49:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:06 INFO - 'LOGNAME': 'cltbld', 21:49:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:06 INFO - 'MOZ_NO_REMOTE': '1', 21:49:06 INFO - 'NO_EM_RESTART': '1', 21:49:06 INFO - 'PAGER': '/bin/cat', 21:49:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:06 INFO - 'PWD': '/builds/slave/test', 21:49:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:06 INFO - 'SHELL': '/bin/bash', 21:49:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RiNayVu7w0/Listeners', 21:49:06 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 21:49:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:06 INFO - 'USER': 'cltbld', 21:49:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:06 INFO - 'XPC_FLAGS': '0x0', 21:49:06 INFO - 'XPC_SERVICE_NAME': '0', 21:49:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:06 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)) 21:49:06 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)) 21:49:06 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)) 21:49:06 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)) 21:49:06 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)) 21:49:06 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)) 21:49:06 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)) 21:49:06 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)) 21:49:06 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)) 21:49:06 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)) 21:49:06 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)) 21:49:06 INFO - Cleaning up... 21:49:06 INFO - Return code: 0 21:49:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:49:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:49:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:49:07 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 0x10fb583f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10face570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb98257e7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f96ed30>, '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 0x10f7d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f966b20>, '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.uq8EnFstTC/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.RiNayVu7w0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 21:49:07 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 21:49:07 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 21:49:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uq8EnFstTC/Render', 21:49:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:07 INFO - 'HOME': '/Users/cltbld', 21:49:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:07 INFO - 'LOGNAME': 'cltbld', 21:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:07 INFO - 'MOZ_NO_REMOTE': '1', 21:49:07 INFO - 'NO_EM_RESTART': '1', 21:49:07 INFO - 'PAGER': '/bin/cat', 21:49:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:07 INFO - 'PWD': '/builds/slave/test', 21:49:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:07 INFO - 'SHELL': '/bin/bash', 21:49:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RiNayVu7w0/Listeners', 21:49:07 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 21:49:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:07 INFO - 'USER': 'cltbld', 21:49:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:07 INFO - 'XPC_FLAGS': '0x0', 21:49:07 INFO - 'XPC_SERVICE_NAME': '0', 21:49:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:49:07 INFO - Cleaning up... 21:49:07 INFO - Return code: 0 21:49:07 INFO - Running post-action listener: _resource_record_post_action 21:49:07 INFO - Running post-action listener: _start_resource_monitoring 21:49:07 INFO - Starting resource monitoring. 21:49:07 INFO - [mozharness: 2016-12-01 05:49:07.431896Z] Finished create-virtualenv step (success) 21:49:07 INFO - [mozharness: 2016-12-01 05:49:07.432932Z] Running install step. 21:49:07 INFO - Running pre-action listener: _resource_record_pre_action 21:49:07 INFO - Running main action method: install 21:49:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:49:07 INFO - Reading from file tmpfile_stdout 21:49:07 INFO - Detecting whether we're running mozinstall >=1.0... 21:49:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:49:07 INFO - Reading from file tmpfile_stdout 21:49:07 INFO - Output received: 21:49:07 INFO - Usage: mozinstall [options] installer 21:49:07 INFO - Options: 21:49:07 INFO - -h, --help show this help message and exit 21:49:07 INFO - -d DEST, --destination=DEST 21:49:07 INFO - Directory to install application into. [default: 21:49:07 INFO - "/builds/slave/test"] 21:49:07 INFO - --app=APP Application being installed. [default: firefox] 21:49:07 INFO - mkdir: /builds/slave/test/build/application 21:49:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:49:40 INFO - Reading from file tmpfile_stdout 21:49:40 INFO - Output received: 21:49:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:49:40 INFO - Running post-action listener: _resource_record_post_action 21:49:40 INFO - [mozharness: 2016-12-01 05:49:40.798243Z] Finished install step (success) 21:49:40 INFO - [mozharness: 2016-12-01 05:49:40.798400Z] Running run-tests step. 21:49:40 INFO - Running pre-action listener: _resource_record_pre_action 21:49:40 INFO - Running main action method: run_tests 21:49:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:49:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:49:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:49:40 INFO - 2016-11-30 21:49:40.812 screenresolution[1627:7596] starting screenresolution argv=screenresolution get 21:49:40 INFO - 2016-11-30 21:49:40.815 screenresolution[1627:7596] Display 0: 1600x1200x32@60 21:49:40 INFO - 2016-11-30 21:49:40.821 screenresolution[1628:7598] starting screenresolution argv=screenresolution list 21:49:40 INFO - Available Modes on Display 0 21:49:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:49:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:49:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:49:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:49:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:49:41 INFO - Intel Iris: 21:49:41 INFO - Chipset Model: Intel Iris 21:49:41 INFO - Type: GPU 21:49:41 INFO - Bus: Built-In 21:49:41 INFO - VRAM (Dynamic, Max): 1536 MB 21:49:41 INFO - Vendor: Intel (0x8086) 21:49:41 INFO - Device ID: 0x0a2e 21:49:41 INFO - Revision ID: 0x0009 21:49:41 INFO - Displays: 21:49:41 INFO - Display: 21:49:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:49:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:49:41 INFO - Display Serial Number: 1600x1200 60 21:49:41 INFO - Main Display: Yes 21:49:41 INFO - Mirror: Off 21:49:41 INFO - Online: Yes 21:49:41 INFO - Rotation: Supported 21:49:41 INFO - Return code: 0 21:49:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:49:41 INFO - Python 2.7.3 21:49:41 INFO - Return code: 0 21:49:41 INFO - grabbing minidump binary from tooltool 21:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:41 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 0x10f96ed30>, '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 0x10f7d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f966b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:49:41 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 21:49:41 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 21:49:41 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 21:49:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:49:41 INFO - Return code: 0 21:49:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:49:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:49:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:49:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:49:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:49:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0309', '--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 21:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0309 --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 21:49:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uq8EnFstTC/Render', 21:49:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:41 INFO - 'HOME': '/Users/cltbld', 21:49:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:41 INFO - 'LOGNAME': 'cltbld', 21:49:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:49:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:41 INFO - 'MOZ_NO_REMOTE': '1', 21:49:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:49:41 INFO - 'NO_EM_RESTART': '1', 21:49:41 INFO - 'PAGER': '/bin/cat', 21:49:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:41 INFO - 'PWD': '/builds/slave/test', 21:49:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:49:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:41 INFO - 'SHELL': '/bin/bash', 21:49:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RiNayVu7w0/Listeners', 21:49:41 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 21:49:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:41 INFO - 'USER': 'cltbld', 21:49:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:41 INFO - 'XPC_FLAGS': '0x0', 21:49:41 INFO - 'XPC_SERVICE_NAME': '0', 21:49:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0309', '--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 21:49:41 INFO - mozversion application_buildid: 20161130192045 21:49:41 INFO - mozversion application_changeset: cd4cdcc9ad6c45dad8b8d8c0d40e459db2bca8a1 21:49:41 INFO - mozversion application_display_name: Nightly 21:49:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:49:41 INFO - mozversion application_name: Firefox 21:49:41 INFO - mozversion application_remotingname: firefox 21:49:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:49:41 INFO - mozversion application_vendor: Mozilla 21:49:41 INFO - mozversion application_version: 53.0a1 21:49:41 INFO - mozversion platform_buildid: 20161130192045 21:49:41 INFO - mozversion platform_changeset: cd4cdcc9ad6c45dad8b8d8c0d40e459db2bca8a1 21:49:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:49:41 INFO - mozversion platform_version: 53.0a1 21:49:41 INFO - using testdate: 1480571381 21:49:41 INFO - actual date: 1480571381 21:49:41 INFO - starting webserver on 'localhost:49241' 21:49:41 INFO - SUITE-START | Running 2 tests 21:49:41 INFO - TEST-START | dromaeo_css 21:49:41 INFO - operating with platform_type : mac_ 21:49:41 INFO - Initialising browser for dromaeo_css test... 21:49:41 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpmaCDFm/profile http://localhost:49241/getInfo.html) 21:49:44 INFO - PROCESS | 1638 | 2016-11-30 21:49:44.715 plugin-container[1640:7774] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f47, name = 'com.apple.tsm.portname' 21:49:44 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:49:44 INFO - PROCESS | 1638 | 2016-11-30 21:49:44.718 plugin-container[1640:7774] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9513, name = 'com.apple.CFPasteboardClient' 21:49:44 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:49:44 INFO - PROCESS | 1638 | 2016-11-30 21:49:44.718 plugin-container[1640:7774] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:49:44 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 21:49:44 INFO - PROCESS | 1638 | colorDepth:24 21:49:44 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 21:49:44 INFO - PROCESS | 1638 | __metrics 21:49:44 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 21:49:45 INFO - TEST-INFO | 1638: exit 0 21:49:45 INFO - Browser initialized. 21:49:45 INFO - Running cycle 1/1 for dromaeo_css test... 21:49:45 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpmaCDFm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:49:48 INFO - PROCESS | 1641 | 2016-11-30 21:49:48.147 plugin-container[1642:7893] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 21:49:48 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:49:48 INFO - PROCESS | 1641 | 2016-11-30 21:49:48.148 plugin-container[1642:7893] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 21:49:48 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:49:48 INFO - PROCESS | 1641 | 2016-11-30 21:49:48.148 plugin-container[1642:7893] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:49:57 INFO - PROCESS | 1641 | RSS: Main: 242855936 21:49:57 INFO - PROCESS | 1641 | 21:50:47 INFO - PROCESS | 1641 | 1480571447506 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:53:00 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 21:53:01 INFO - PROCESS | 1641 | RSS: Main: 251400192 21:53:01 INFO - PROCESS | 1641 | 21:56:04 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 21:56:04 INFO - PROCESS | 1641 | RSS: Main: 262365184 21:56:04 INFO - PROCESS | 1641 | 21:59:06 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 21:59:07 INFO - PROCESS | 1641 | RSS: Main: 266780672 21:59:07 INFO - PROCESS | 1641 | 22:02:09 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 22:02:10 INFO - PROCESS | 1641 | RSS: Main: 266223616 22:02:10 INFO - PROCESS | 1641 | 22:05:12 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 22:05:13 INFO - PROCESS | 1641 | RSS: Main: 270888960 22:05:13 INFO - PROCESS | 1641 | 22:08:16 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 22:08:16 INFO - PROCESS | 1641 | RSS: Main: 274817024 22:08:16 INFO - PROCESS | 1641 | 22:08:16 INFO - PROCESS | 1641 | __start_tp_report 22:08:16 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 22:08:16 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 22:08:16 INFO - PROCESS | 1641 | |i|pagename|runs| 22:08:16 INFO - PROCESS | 1641 | |0;dojo.html;2705;2928.8828446862126;2973.122928736025;3347.727863411903;3375.139339468435;5635.295588051494;5685.943140568593;5711.343195532515;5735.913961290584;5783.334916484606;5603.691796951166;5605.495505404523;5690.914636280449;5697.17390978309;5716.942365662356;4977.129002424579;5083.389993200821;5139.588832893378;5144.922826157611;5151.4075881273575;4509.323601459774;5033.320501732274;5132.5124113209185;5184.53339199472;5225.817096401626;4051.250518654029;4089.0799570096788;4195;5268.710220937847;5302.178162384817;5112.821051263188;5218.373795144607;5230.92153617696;5247.947520524822;5272.367315922075;4082.1835632873544;4971.7514124294;5162.1224391853475;5201.323827902348;5232.581393488512;5483.287172667528;5499.120140777496;5673.177389278531;5694.032014557535;5725.799597014085;6106.582013105394;6148.78479253224;6174.938250617482;6189.876202475925;6201.906971395432;6204.13142160098;6207.596506227081;6209.5032397408095;6220.066989951498;6223.1287619733275;5731.598788084794;5965.343812180657;5975;5977.820665380045;5989.3711160327975;6209.192804935329;6218.689065546704;6222.937770622327;6226.283977342664;6265.686715664198;6179.0422484514975;6182.041783523561;6214.285357183981;6221.222347206599;6228.968855155786;5614.9157762633595;5842.152887831241;6298.779542715983;6309.873802523924;6326.841828954221;6018.849528761816;6124.014786914997;6136.509079273647;6149.877002459926;6156.907646385308;6266.717997690115;6268.341824108494;6283.08895210192;6294.874102517924;6304.211973503312;4912.6561140719805;4953.851384458472;4955.182394904872;4963.503649635008;4969.975150124226;4346;4371.1039236956985;4393.934090988638;4412.271975124133;4471.19518486669;5693.686847223443;5844.970775146097;5883.441073098049;5889.440503152279;5895.7052147393315;6282.968585157137;6286.371362863677;6295.338989406138;6296.401841825019;6300.684965751694;5657.57568182388;5688.089905615082;5877.588568800143;5879.735411906475;5936.1095835625;5380.596455265871;5387.568994480433;5388.299521062237;5389.353277606712;5401.513863752281;5221.686698798084;6241.469475094668;6283.6858157091965;6299.527535434769;6303.968480157661;6190.2571691397325;6213.720382582885;6292.1505596743855;6300.653464059521;6313.779017734358;6277.905831412441;6283.5287353449585;6303;6321.083442900662;6321.96839015802;5128.3076784633595;6079.665618390854;6197.566170368031;6217.160683307877;6249.375062493714;6195.101710252078;6226.283977342482;6229.53278504123;6278.309385967631;6288;4924.310596516562;5120.513551212629;5674.858128546819;6174.166487524307;6189.938100618936;6006.819795406145;6151.96924015377;6154.353792851865;6181.81454556364;6198.473129784019;5839.357670656309;5892.823215303548;5902.881942361215;5977.6114552553945;6000.369961154016;5151.896962060663;6140.995089002448;6159.84600384994;6167.167432396569;6176.567640265138;5936.821895343147;5978.3423823378525;6001.039833626599;6009.489193418433;6017.3681763414215 22:08:16 INFO - PROCESS | 1641 | |1;ext.html;456.8446728112442;468.82419092840206;478.7127723365983;502.5703023914553;524.7612336386944;37094.44358334624;38709.41935870961;38845.726970033276;40147.59852401475;40255;39792.8062158135;40073.19853602926;40191.995200120014;40245.79877100618;40285.79142625726;8331.66673333069;9242.768930776718;9426;9444;9444.244460443148;8354.749357519284;11058.723531911686;11282.830757538644;11308.660740177807;11433.542658293658;11287;11302.943485282562;11332.036776873976;11337.14971377146;11379.487923043442;10994.175436842268;11142.220044596881;11155.832662510069;11226.887731122666;11230.719232019183;11141.498632561554;11192.608258710907;11229.831552526719;11242.550297988071;11245.493952772063;37990.81004594987;38065.42901856474;38130.85607431754;38190;38327.233455331014;3337.9666203337897;3348.4809854472596;3428.451447768345;3515.6660117288816;3613.94579081314;28727;28953.13140605785;29082.709172908424;29263.829446822103;29484.6731897063;3151.8739250430185;3167.5407065975305;3179.443597370451;3266.4610339293986;3288.8026718396973;402299.9999999971;404055.9391609128;466835;467322;472025;44248.55751442509;47348.053038939026;54362;54828.72585637126;54996.175057374174;50024.74987625112;56080;56141;56160.43839561634;56494.717526412926;14873.884458665427;15086.622834429008;15169;15366.615834604225;15370;10776;11030.724231894106;11838.052955763518;11892.940535297268;11978.940105299593;14236.715265694627;14620.976531642684;14646.340914658866;14652.55370664058;14771.704565908622;5536.916946245809;6469.482441404676;6511.804645860537;6528.51036172289;6595.307492713292;853.6585365853584;877.644553955649;890.1899271662755;929.6142101028133;976.6630512473185;27332.180034598994;28407.857960710066;28580.142595721747;28841.27896802555;28954.27614309617;859.7420773767844;974.9951250243703;1007.1640538353149;1051.7370657335666;1079.3416016230094;13451.32743362828;14653.560393188221;14753.40986360555;14979.951403401876;15185;448241;453841;454976;455956;456238;50795;51229.975400491785;51296.48703512992;51340.716482088246;51371.48628513667;1146.8165093585153;1179.4751335655722;1313.507434711983;1320.8348956380453;1340.336533415946;26899.596506052032;27484.450310994067;28162.71837281601;28178;28221.858890706237;43281.3507797377;43986;44269.33595995998;44298.89252768578;44540.886477838314;411299;413504;413538;414297;416177;35783.92648220558;36264;36680.98254595916;36774.26451471009;36786;41379.58620413878;41804.790976044926;42461.363079553215;42694;43094;33017.17457063593;34431.655683443845;34557.82721086378;35364.469532956515;35384;29320.560191596716;34990.47514287339;35326.76356327516;35864.82067589645;36027.27945441129;34517;35186.648133518334;35214.11964700903;35450.82274588611;35581.466278006366;29954.85022574873;30123.096307110824;31704;31860;32001.6799832008;23777;23913.880430597736;23975.640365394185;24311.75688243095;24340.75659243385 22:08:16 INFO - PROCESS | 1641 | |2;jquery.html;335.24067986011687;365.30774182923346;369.79476390603213;373.60024773492364;380.69924759440045;17955.832870863385;19234.422967310984;19713.507162320948;20275.797242027595;20510.84636113209;17788.46634600964;19698.70451943222;19839.10724017419;20182.495437614067;20249.898750506225;5545.833624991246;5618.297712785902;5826.533877289807;5828.795992140276;5840.853978650525;5724.656520608777;5883.382244864292;5884.882302353951;5889.322727886305;5900.321463031742;5593.300837395325;5816.418358164192;5824.767009319622;5826.980278451275;5845.123231515286;5740.138979153118;5779.335376431701;5782.971085144568;5849.824505264827;5858;4990.026944745776;5005.6996580205305;5009.2486127080865;5278.709670968095;5295.232191332274;12818.935905320506;13011.804822927663;13023.934880325538;13754;24076;1803.7023891057959;2084.4396579533854;2119.671450925109;2304.8271379646426;2769.9307517312227;9871;10215.591376344937;10243.87317395086;12269.631911042683;14461.421543138262;1642.958926026847;1778.0931724820305;1786.6516029374277;1851.5371157210698;1861.1903821837543;91202;106285.40571891428;114065.42967285194;118176;120643;10428.322159059637;11392.658220253406;11601.955823975883;11649.301041937511;11756.647300580997;14317.64205894861;14453.132812031312;14454.783178252335;14471.565853024425;14481.20353380553;8133.024037115584;8290.336773058141;8320.583970801435;8338.560780807868;8453.027901791373;6798.810208213543;7838.059432867978;7983.481073730189;8011.158828322942;8242.876356854533;3750.887473375803;3877;4130.834766609362;4477.813379454447;4539.341795439643;1893.280553389703;1955.8044195580328;1956.3544030469911;1957.2366776956999;1957.784643689193;838.5471845203643;926.9026752191057;943.6272672294406;965.4931161140457;970.2335155227291;19848.60302793965;20335.694964575545;21572;21680.783192168194;22435.77564224337;857.2070834478083;858.8277001892372;882.1575395497284;916.5966974531185;1018.7351288665001;9064.637414503479;10741.462926853626;11203.887961120448;11220.270682405617;11301;562023;568582;581203;590637;593317;110243.44878275557;120828;126503;137523;138762;931.5994122527243;972.601233494264;1009.3338396658318;1017.8880323164447;1018.8166130096654;18042.45872623771;18809.247630094644;18881.71677424867;18902.7164592534;18936.810631893506;34075.488867667504;41701;44413.555864442234;53719.194212087634;53899;56088;76991;77022;77884.61057694675;78245.82631260421;7759;7942.364610831004;7964.9203507966495;8119.918800812153;8186.54973976437;9870.802583948138;12243.449044793184;12320.507179712711;12599.496020159091;12633.873661263637;8577.614007369559;10681;10854.511546980562;11100.833487497532;11123.83199764013;10612.522436490222;10715.678529644123;10806.459677016275;11055.557777689124;11106.222564420414;9235.399699019543;10750.408727520062;11013.449327533752;11158.386288754196;11387.54449822031;7941.722039728813;7978.281954624112;8157;8163.551004694798;8190.058143313357;10243.7439064024;10417.531211095367;10714.785704286027;10918.672439826818;11038.337699738042 22:08:16 INFO - PROCESS | 1641 | |3;mootools.html;1313.619050475362;1450.75235297644;1608.7345587977984;1786.1962117047333;1789.418439007323;7537.962310188442;7803.882941755878;8130.51216926984;8537.957310213447;8538.060813310538;8021.518708877456;8305.91694083059;8332.291755200815;8458.281046111088;8468.66125354985;2281.9885900570475;2323.779240972105;2331.428799944016;2334.241371554243;2338.6959695239593;2234.273860995175;2238.87686177261;2239.731232252132;2243.596152692515;2350.4828937633692;2302.7466978632337;2305.3890718959487;2316.3977365884907;2323.5120624668757;2325.93022209334;1886.962260754791;2221.522372689871;2247.438140464884;2251.2458326460655;2253.9436514087115;2021.82814460771;2032.9796702032938;2062.4946888012532;2135.2099723102397;2297.85063970843;8290.08809031006;8345.916540834574;8369;8518.87221691675;8520.40357174998;4487.461504619434;5097.668651537638;5256.053910296145;5270.973645131749;5277.129273669878;3233.8383080845865;3276.737860971117;3492.8602855885733;3572.8570857165682;3601.7478776485655;1038.7974345002729;1067.759754055339;1075.8386242063675;1088.2491081153978;1097.3415950429758;10882.782344353147;10889.01998820102;10959.616413425492;11010.229283950126;11025.724356891062;4477.619402350771;4608.124456353282;4672.486026537078;4687.320338550891;4707.976460117678;9371.578278977462;9476.289278304017;9487.620495180116;9494.810103797885;9498.192653624381;5196.844094677166;5200.609954253446;5210.817621383233;5213.322268105122;5216.973915130476;4253.21315556623;4278.507971583245;4699.295105734099;4760.500147484534;4761.761911904391;3469.7397695172963;3588.1567831559564;3762.5861155272896;3841;3875.224955008953;1256.0454054918328;1256.4785613970282;1264.6395777203452;1266.468083404972;1278.1691900264902;763.4541302968404;860.9096044915319;902.201551626815;903.3179949138358;905.8867641544807;5582.553395728332;5697.6296540724725;5726.3700992890745;5793.681347525927;5864.853378665567;418.4288446270879;430.22129944800014;432.61065041462933;434.50032462668185;434.8369361489441;3648.7445878788762;3729.1589662647516;3795.7912314822397;3869.3228684980018;3924.4113382992778;9653.420794752337;9765.755856103513;9838.754031149136;9955.751106222402;9985.251106167067;9445.433274003582;9707.854382184272;9807.26445516589;9973.950130249303;10083.49582520871;7751.612419379121;7894.328982036591;7907.525548467111;7970.963774709251;7974.242446967645;4755.738434386142;5740.741666625096;5830.4461076197695;5852.707364631666;5905.852353691193;9160.954195228982;9443.716688499357;9449.905500945177;9461.432314061178;9471.905280947378;7367.594782286811;7943.563104029334;9177.567944494847;9603.759906002126;9643.08390702882;6554.737810487718;6768.7292508299115;6966.12923384577;7120.893186602101;7304.707811687473;7819;7967.964164658557;8034.879476807736;8071;8114.634841432267;7071.328223818729;7125;7213;7257.0565826442225;7298.927010729824;6690.663981094737;6818.659067046528;6824.590524568542;6863.176418829772;6998.2651821558;6434.806955791334;6445.548811583144;6490.221173459215;6497.180789378982;6510.121133646897;4819.421669399694;4842.661013729005;4871.171900776805;4889.731064791471;4979.0788704089855;4774.1883879739835;4785;4875.585575226146;4946.9364086721525;4960.627952903475 22:08:16 INFO - PROCESS | 1641 | |4;prototype.html;297.6621534558276;301.1732793481892;307.86915560886615;309.66865453964266;311.5342562868511;5252.842414727555;5266.52601265886;5362;5465.836024919254;5504.908134908734;5179.378474583046;5307.73461326934;5355.223492593572;5362.329708786402;5371.57027437805;3503.597086335066;3848.2688289224957;4131.958680413202;4143.627073563379;4145.274576949037;3262.9021129366038;3757.3612485877466;4158.4178215049915;4166.166766646667;4168.415162959852;3316.5522654441584;3451.2924850405734;3735.2529494101154;4033.7781422021776;4052.527957945651;3319.9170020749552;3799.601041890603;4015.638592526687;4030.254402935437;4041.3937909313836;3486.6513348665185;3507.158282012324;3510.5260789793306;3513.489199644152;3536.9469457958153;4978.975105124451;5157.6647517911215;5212.061828870802;5287.682739035632;5313.760880760375;3607.881556717426;4503.770633536387;4597.632189424838;4597.95402045982;4638.095571363586;5081.949180508184;5185.662931909414;5634.661920284796;5659.320881494205;5676.3188417389765;3114.5951026366656;3155.9684403156134;3384.3569721752788;3845.0393197237972;3856.151646637735;4873.513578358599;5714.228579141804;5880.5883588148845;5889.411058894077;5941.544321641182;3415.624281329052;3625;3899.512560929884;3921.8039098044983;3923.705722070856;3644.5444319460066;3951.78265195417;3976.522817261889;3987.1228329767405;4001;3838.5393752749846;4011.6590089842107;4305.9569404306185;4318.762468064287;4614.74618895964;4733.502982186889;5717.171010203498;5727.914081288784;5731.914021289684;5732.082866741302;1033.6278939581744;1229.4079167478137;1241.3358853013774;1281.8591453606298;1286.9613911582667;751.0124186694479;757.458417231671;869.2437579306044;882.43965082172;884.3455842676502;4865.905171336421;4911.263310503453;5083.822066227664;5103.897922041539;5122.718250496258;4812.783424745825;4830.140395947586;4995.425526064477;5626.268585083994;5655.406182350876;5158.807506809666;5371.973140134352;5412.806444197863;5416.2688037114485;5432.70120143388;2642.748938850806;2729.713380095101;2803.046964032198;2899.6375453068367;2927.2974486123603;5288.576913846883;5584.4921871094575;5624.3250809903075;5631.915521267184;5639.943600564024;4504.6396288296855;4946.950530494721;5135.794568217229;5248.921266181011;5374.54316383104;4847.781849816767;5458.317710286215;5678.387941546935;5718.942810571924;5855.443732845373;4663.976680116578;5200.869978250574;5454.318210223722;5479.342478902558;5585.245991791056;5606.411326810626;5839.693321138042;5878.265216847894;5884.293884733859;5905.734241959208;5239.318888544465;5359.68687671523;5426.403095659553;6083.908741368795;6093.745314233762;3413.7951722896705;3847.2882516734494;3931.9017024574614;3944.2505923874373;3948.1906209227614;5026.899462010812;5566.385748133158;5920.585559010828;5926.614770040106;5967.358976281557;4906.582940450102;5349.705766182898;5704;5750.25246717924;5778.768849246151;4808.903821923612;5755.8561035974435;5759.337676167127;5771.480566749013;5790.783935373619;5397.622166448468;5437.755301011385;5534.778608855761;5651.745671444877;5658.575606829587;4355.756785255532;5255.421903590678;5272.235525848807;5551.8889622206525;5771.480566749013;4898.926516102332;5303.920441193308;5315.255864178941;5531.280933478622;5614.578906581941 22:08:16 INFO - PROCESS | 1641 | |5;yui.html;679.4055201698513;693.1716598664592;707.5542408282781;756.8145804277952;797.4298376660688;13303.401346939387;14671.779923301146;15036.473723419682;15390.769138462918;15501.06993580386;14121.43514259431;14231.07498012631;14246.358913848875;14288.714225715505;14298.571042868705;799.3884678221166;808.4017826808164;819.6557445872718;820.5240960243075;821.4250024982506;784.2157842157842;817.4073796497552;818.7298191596789;820.4175035724643;824.7938015496126;853.4324674091722;858.7938894665267;864.2351518905782;864.9610767515445;868.3443999780158;841.6717480182733;857.5669287010082;863.1627321498169;863.1756672377865;864.8918885139358;640.1933563709713;640.2317219336768;641.1216633212529;641.3009819296942;641.8234985379012;14120.435182592684;14755.77866332006;14932.62668433287;14959.925200374037;14978.251087445693;11416.600418985296;13519.188848669111;13825.512700654439;13938.860611393859;14101.730844223966;11682.24065435744;11884.346360950145;11983.921447069719;12172.026237901035;12357.443915023845;3114.4549703801745;3360.428727116396;3728.3848165053005;3787.1668232988973;3869.8839034829;80911.59544202242;99630;100609;100769;113129;9072.954635226914;9414.070115626417;9636.75908102303;9931.702048938543;10263.897361026444;17296.481105566854;21348.252811151535;21369.465763356042;21444.892775536024;21473.67789483159;7311.75700130979;7425.9628701856145;7750.806229844186;7758.5004880186625;7897.368210543143;3674.9632503675157;4278.957210427918;4375.299952007664;4393.692441529126;4418.624416924597;3453.827308634558;3574.910627234288;3579.8926032219074;3581.4985901973746;3589.892303230855;603.7796204385368;686.8385929306609;691.3881215124551;692.4460431654657;709.521073275537;556.5224329405435;587.8794847056344;597.8744463662592;628.3779058731914;643.4080645805869;9181.35730498872;9253.398529095588;9313.53432328381;9440.90559094414;9525.761855953657;535.611681530887;593.964362138273;605.5458406195354;627.1188979483833;656.4157899469476;3277.705006549422;3967.26605577969;4253.766042867672;4448.933266001012;4530.139273538017;81854;104535;106997.9300206988;109349;112841;94104;95149;95545.52227238819;96418;97950.34844076524;71327.64336178286;74617;75393.62303188558;75703.62148189223;77332.22667773363;8387.266613354106;8783.516906569945;8820;8832.911670883466;9012;46987;48996.02007959892;49745.502544974086;50533.74733126311;50712.47862564129;62441.43896402626;62855;65164.34835651583;76548;76712.84930726155;11477.426128693365;11532.59635912739;11789.292642441482;11868.347240901834;12039.578614748441;12610.936945315214;12794.488220471078;13004.739905202032;13023.297419790564;13095.148815326973;11801.17391782567;12061.577844775391;12148.878511215129;12531.18547294423;12585.496580136693;12074.879251207376;12161.574344897886;12394.81407778902;12432.81350779721;12449.93775031119;10327.793444131226;11306.925842044993;11381.54473821038;11663.475143618387;11748.647540573797;9224.446533208029;9460.101290377403;9895.604175833174;10107.090361867467;10108.039736225046;10017.998200179924;10147.543360548942;10152.898471015491;10160.390376577428;10169.983001699771 22:08:16 INFO - PROCESS | 1641 | __end_tp_report 22:08:16 INFO - PROCESS | 1641 | __start_cc_report 22:08:16 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,380 22:08:16 INFO - PROCESS | 1641 | __end_cc_report 22:08:16 INFO - PROCESS | 1641 | __startTimestamp1480572496837__endTimestamp 22:08:16 INFO - PROCESS | 1641 | 22:08:16 INFO - PROCESS | 1641 | ------- Summary: start ------- 22:08:16 INFO - PROCESS | 1641 | Number of tests: 6 22:08:16 INFO - PROCESS | 1641 | 22:08:16 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5675.00 Median:5936.82 stddev:730.13 (12.3%) stddev-sans-first:694.20 22:08:16 INFO - PROCESS | 1641 | Values: 2705.0 2928.9 2973.1 3347.7 3375.1 5635.3 5685.9 5711.3 5735.9 5783.3 5603.7 5605.5 5690.9 5697.2 5716.9 4977.1 5083.4 5139.6 5144.9 5151.4 4509.3 5033.3 5132.5 5184.5 5225.8 4051.3 4089.1 4195.0 5268.7 5302.2 5112.8 5218.4 5230.9 5247.9 5272.4 4082.2 4971.8 5162.1 5201.3 5232.6 5483.3 5499.1 5673.2 5694.0 5725.8 6106.6 6148.8 6174.9 6189.9 6201.9 6204.1 6207.6 6209.5 6220.1 6223.1 5731.6 5965.3 5975.0 5977.8 5989.4 6209.2 6218.7 6222.9 6226.3 6265.7 6179.0 6182.0 6214.3 6221.2 6229.0 5614.9 5842.2 6298.8 6309.9 6326.8 6018.8 6124.0 6136.5 6149.9 6156.9 6266.7 6268.3 6283.1 6294.9 6304.2 4912.7 4953.9 4955.2 4963.5 4970.0 4346.0 4371.1 4393.9 4412.3 4471.2 5693.7 5845.0 5883.4 5889.4 5895.7 6283.0 6286.4 6295.3 6296.4 6300.7 5657.6 5688.1 5877.6 5879.7 5936.1 5380.6 5387.6 5388.3 5389.4 5401.5 5221.7 6241.5 6283.7 6299.5 6304.0 6190.3 6213.7 6292.2 6300.7 6313.8 6277.9 6283.5 6303.0 6321.1 6322.0 5128.3 6079.7 6197.6 6217.2 6249.4 6195.1 6226.3 6229.5 6278.3 6288.0 4924.3 5120.5 5674.9 6174.2 6189.9 6006.8 6152.0 6154.4 6181.8 6198.5 5839.4 5892.8 5902.9 5977.6 6000.4 5151.9 6141.0 6159.8 6167.2 6176.6 5936.8 5978.3 6001.0 6009.5 6017.4 22:08:16 INFO - PROCESS | 1641 | 22:08:16 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:57726.76 Median:35374.23 stddev:115917.05 (327.7%) stddev-sans-first:116162.48 22:08:16 INFO - PROCESS | 1641 | Values: 456.8 468.8 478.7 502.6 524.8 37094.4 38709.4 38845.7 40147.6 40255.0 39792.8 40073.2 40192.0 40245.8 40285.8 8331.7 9242.8 9426.0 9444.0 9444.2 8354.7 11058.7 11282.8 11308.7 11433.5 11287.0 11302.9 11332.0 11337.1 11379.5 10994.2 11142.2 11155.8 11226.9 11230.7 11141.5 11192.6 11229.8 11242.6 11245.5 37990.8 38065.4 38130.9 38190.0 38327.2 3338.0 3348.5 3428.5 3515.7 3613.9 28727.0 28953.1 29082.7 29263.8 29484.7 3151.9 3167.5 3179.4 3266.5 3288.8 402300.0 404055.9 466835.0 467322.0 472025.0 44248.6 47348.1 54362.0 54828.7 54996.2 50024.7 56080.0 56141.0 56160.4 56494.7 14873.9 15086.6 15169.0 15366.6 15370.0 10776.0 11030.7 11838.1 11892.9 11978.9 14236.7 14621.0 14646.3 14652.6 14771.7 5536.9 6469.5 6511.8 6528.5 6595.3 853.7 877.6 890.2 929.6 976.7 27332.2 28407.9 28580.1 28841.3 28954.3 859.7 975.0 1007.2 1051.7 1079.3 13451.3 14653.6 14753.4 14980.0 15185.0 448241.0 453841.0 454976.0 455956.0 456238.0 50795.0 51230.0 51296.5 51340.7 51371.5 1146.8 1179.5 1313.5 1320.8 1340.3 26899.6 27484.5 28162.7 28178.0 28221.9 43281.4 43986.0 44269.3 44298.9 44540.9 411299.0 413504.0 413538.0 414297.0 416177.0 35783.9 36264.0 36681.0 36774.3 36786.0 41379.6 41804.8 42461.4 42694.0 43094.0 33017.2 34431.7 34557.8 35364.5 35384.0 29320.6 34990.5 35326.8 35864.8 36027.3 34517.0 35186.6 35214.1 35450.8 35581.5 29954.9 30123.1 31704.0 31860.0 32001.7 23777.0 23913.9 23975.6 24311.8 24340.8 22:08:16 INFO - PROCESS | 1641 | 22:08:16 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:33483.88 Median:13190.41 stddev:96683.55 (733.0%) stddev-sans-first:96922.74 22:08:16 INFO - PROCESS | 1641 | Values: 335.2 365.3 369.8 373.6 380.7 17955.8 19234.4 19713.5 20275.8 20510.8 17788.5 19698.7 19839.1 20182.5 20249.9 5545.8 5618.3 5826.5 5828.8 5840.9 5724.7 5883.4 5884.9 5889.3 5900.3 5593.3 5816.4 5824.8 5827.0 5845.1 5740.1 5779.3 5783.0 5849.8 5858.0 4990.0 5005.7 5009.2 5278.7 5295.2 12818.9 13011.8 13023.9 13754.0 24076.0 1803.7 2084.4 2119.7 2304.8 2769.9 9871.0 10215.6 10243.9 12269.6 14461.4 1643.0 1778.1 1786.7 1851.5 1861.2 91202.0 106285.4 114065.4 118176.0 120643.0 10428.3 11392.7 11602.0 11649.3 11756.6 14317.6 14453.1 14454.8 14471.6 14481.2 8133.0 8290.3 8320.6 8338.6 8453.0 6798.8 7838.1 7983.5 8011.2 8242.9 3750.9 3877.0 4130.8 4477.8 4539.3 1893.3 1955.8 1956.4 1957.2 1957.8 838.5 926.9 943.6 965.5 970.2 19848.6 20335.7 21572.0 21680.8 22435.8 857.2 858.8 882.2 916.6 1018.7 9064.6 10741.5 11203.9 11220.3 11301.0 562023.0 568582.0 581203.0 590637.0 593317.0 110243.4 120828.0 126503.0 137523.0 138762.0 931.6 972.6 1009.3 1017.9 1018.8 18042.5 18809.2 18881.7 18902.7 18936.8 34075.5 41701.0 44413.6 53719.2 53899.0 56088.0 76991.0 77022.0 77884.6 78245.8 7759.0 7942.4 7964.9 8119.9 8186.5 9870.8 12243.4 12320.5 12599.5 12633.9 8577.6 10681.0 10854.5 11100.8 11123.8 10612.5 10715.7 10806.5 11055.6 11106.2 9235.4 10750.4 11013.4 11158.4 11387.5 7941.7 7978.3 8157.0 8163.6 8190.1 10243.7 10417.5 10714.8 10918.7 11038.3 22:08:16 INFO - PROCESS | 1641 | 22:08:16 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5397.30 Median:5205.71 stddev:2986.32 (57.4%) stddev-sans-first:2978.92 22:08:16 INFO - PROCESS | 1641 | Values: 1313.6 1450.8 1608.7 1786.2 1789.4 7538.0 7803.9 8130.5 8538.0 8538.1 8021.5 8305.9 8332.3 8458.3 8468.7 2282.0 2323.8 2331.4 2334.2 2338.7 2234.3 2238.9 2239.7 2243.6 2350.5 2302.7 2305.4 2316.4 2323.5 2325.9 1887.0 2221.5 2247.4 2251.2 2253.9 2021.8 2033.0 2062.5 2135.2 2297.9 8290.1 8345.9 8369.0 8518.9 8520.4 4487.5 5097.7 5256.1 5271.0 5277.1 3233.8 3276.7 3492.9 3572.9 3601.7 1038.8 1067.8 1075.8 1088.2 1097.3 10882.8 10889.0 10959.6 11010.2 11025.7 4477.6 4608.1 4672.5 4687.3 4708.0 9371.6 9476.3 9487.6 9494.8 9498.2 5196.8 5200.6 5210.8 5213.3 5217.0 4253.2 4278.5 4699.3 4760.5 4761.8 3469.7 3588.2 3762.6 3841.0 3875.2 1256.0 1256.5 1264.6 1266.5 1278.2 763.5 860.9 902.2 903.3 905.9 5582.6 5697.6 5726.4 5793.7 5864.9 418.4 430.2 432.6 434.5 434.8 3648.7 3729.2 3795.8 3869.3 3924.4 9653.4 9765.8 9838.8 9955.8 9985.3 9445.4 9707.9 9807.3 9974.0 10083.5 7751.6 7894.3 7907.5 7971.0 7974.2 4755.7 5740.7 5830.4 5852.7 5905.9 9161.0 9443.7 9449.9 9461.4 9471.9 7367.6 7943.6 9177.6 9603.8 9643.1 6554.7 6768.7 6966.1 7120.9 7304.7 7819.0 7968.0 8034.9 8071.0 8114.6 7071.3 7125.0 7213.0 7257.1 7298.9 6690.7 6818.7 6824.6 6863.2 6998.3 6434.8 6445.5 6490.2 6497.2 6510.1 4819.4 4842.7 4871.2 4889.7 4979.1 4774.2 4785.0 4875.6 4946.9 4960.6 22:08:16 INFO - PROCESS | 1641 | 22:08:16 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4484.12 Median:5129.26 stddev:1409.67 (27.5%) stddev-sans-first:1378.16 22:08:16 INFO - PROCESS | 1641 | Values: 297.7 301.2 307.9 309.7 311.5 5252.8 5266.5 5362.0 5465.8 5504.9 5179.4 5307.7 5355.2 5362.3 5371.6 3503.6 3848.3 4132.0 4143.6 4145.3 3262.9 3757.4 4158.4 4166.2 4168.4 3316.6 3451.3 3735.3 4033.8 4052.5 3319.9 3799.6 4015.6 4030.3 4041.4 3486.7 3507.2 3510.5 3513.5 3536.9 4979.0 5157.7 5212.1 5287.7 5313.8 3607.9 4503.8 4597.6 4598.0 4638.1 5081.9 5185.7 5634.7 5659.3 5676.3 3114.6 3156.0 3384.4 3845.0 3856.2 4873.5 5714.2 5880.6 5889.4 5941.5 3415.6 3625.0 3899.5 3921.8 3923.7 3644.5 3951.8 3976.5 3987.1 4001.0 3838.5 4011.7 4306.0 4318.8 4614.7 4733.5 5717.2 5727.9 5731.9 5732.1 1033.6 1229.4 1241.3 1281.9 1287.0 751.0 757.5 869.2 882.4 884.3 4865.9 4911.3 5083.8 5103.9 5122.7 4812.8 4830.1 4995.4 5626.3 5655.4 5158.8 5372.0 5412.8 5416.3 5432.7 2642.7 2729.7 2803.0 2899.6 2927.3 5288.6 5584.5 5624.3 5631.9 5639.9 4504.6 4947.0 5135.8 5248.9 5374.5 4847.8 5458.3 5678.4 5718.9 5855.4 4664.0 5200.9 5454.3 5479.3 5585.2 5606.4 5839.7 5878.3 5884.3 5905.7 5239.3 5359.7 5426.4 6083.9 6093.7 3413.8 3847.3 3931.9 3944.3 3948.2 5026.9 5566.4 5920.6 5926.6 5967.4 4906.6 5349.7 5704.0 5750.3 5778.8 4808.9 5755.9 5759.3 5771.5 5790.8 5397.6 5437.8 5534.8 5651.7 5658.6 4355.8 5255.4 5272.2 5551.9 5771.5 4898.9 5303.9 5315.3 5531.3 5614.6 22:08:16 INFO - PROCESS | 1641 | 22:08:16 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19958.50 Median:47991.51 stddev:29441.01 (61.3%) stddev-sans-first:29488.01 22:08:16 INFO - PROCESS | 1641 | Values: 679.4 693.2 707.6 756.8 797.4 13303.4 14671.8 15036.5 15390.8 15501.1 14121.4 14231.1 14246.4 14288.7 14298.6 799.4 808.4 819.7 820.5 821.4 784.2 817.4 818.7 820.4 824.8 853.4 858.8 864.2 865.0 868.3 841.7 857.6 863.2 863.2 864.9 640.2 640.2 641.1 641.3 641.8 14120.4 14755.8 14932.6 14959.9 14978.3 11416.6 13519.2 13825.5 13938.9 14101.7 11682.2 11884.3 11983.9 12172.0 12357.4 3114.5 3360.4 3728.4 3787.2 3869.9 80911.6 99630.0 100609.0 100769.0 113129.0 9073.0 9414.1 9636.8 9931.7 10263.9 17296.5 21348.3 21369.5 21444.9 21473.7 7311.8 7426.0 7750.8 7758.5 7897.4 3675.0 4279.0 4375.3 4393.7 4418.6 3453.8 3574.9 3579.9 3581.5 3589.9 603.8 686.8 691.4 692.4 709.5 556.5 587.9 597.9 628.4 643.4 9181.4 9253.4 9313.5 9440.9 9525.8 535.6 594.0 605.5 627.1 656.4 3277.7 3967.3 4253.8 4448.9 4530.1 81854.0 104535.0 106997.9 109349.0 112841.0 94104.0 95149.0 95545.5 96418.0 97950.3 71327.6 74617.0 75393.6 75703.6 77332.2 8387.3 8783.5 8820.0 8832.9 9012.0 46987.0 48996.0 49745.5 50533.7 50712.5 62441.4 62855.0 65164.3 76548.0 76712.8 11477.4 11532.6 11789.3 11868.3 12039.6 12610.9 12794.5 13004.7 13023.3 13095.1 11801.2 12061.6 12148.9 12531.2 12585.5 12074.9 12161.6 12394.8 12432.8 12449.9 10327.8 11306.9 11381.5 11663.5 11748.6 9224.4 9460.1 9895.6 10107.1 10108.0 10018.0 10147.5 10152.9 10160.4 10170.0 22:08:16 INFO - PROCESS | 1641 | -------- Summary: end -------- 22:08:16 INFO - PROCESS | 1641 | 22:08:17 INFO - TEST-INFO | 1641: exit 0 22:08:17 INFO - TEST-OK | dromaeo_css | took 1116117ms 22:08:17 INFO - TEST-START | kraken 22:08:17 INFO - operating with platform_type : mac_ 22:08:17 INFO - Initialising browser for kraken test... 22:08:17 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpabE4Id/profile http://localhost:49241/getInfo.html) 22:08:20 INFO - PROCESS | 1652 | 2016-11-30 22:08:20.624 plugin-container[1653:10481] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 22:08:20 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:08:20 INFO - PROCESS | 1652 | 2016-11-30 22:08:20.628 plugin-container[1653:10481] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0f, name = 'com.apple.CFPasteboardClient' 22:08:20 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:08:20 INFO - PROCESS | 1652 | 2016-11-30 22:08:20.628 plugin-container[1653:10481] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:08:20 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 22:08:20 INFO - PROCESS | 1652 | colorDepth:24 22:08:20 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 22:08:20 INFO - PROCESS | 1652 | __metrics 22:08:20 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 22:08:21 INFO - TEST-INFO | 1652: exit 0 22:08:21 INFO - Browser initialized. 22:08:21 INFO - Running cycle 1/1 for kraken test... 22:08:21 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpabE4Id/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:08:24 INFO - PROCESS | 1654 | 2016-11-30 22:08:24.606 plugin-container[1655:10598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 22:08:24 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:08:24 INFO - PROCESS | 1654 | 2016-11-30 22:08:24.607 plugin-container[1655:10598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 22:08:24 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:08:24 INFO - PROCESS | 1654 | 2016-11-30 22:08:24.607 plugin-container[1655:10598] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:08:34 INFO - PROCESS | 1654 | RSS: Main: 243089408 22:08:34 INFO - PROCESS | 1654 | 22:08:37 INFO - PROCESS | 1654 | 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) 22:08:38 INFO - PROCESS | 1654 | RSS: Main: 255000576 22:08:38 INFO - PROCESS | 1654 | 22:08:40 INFO - PROCESS | 1654 | 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) 22:08:41 INFO - PROCESS | 1654 | RSS: Main: 262598656 22:08:41 INFO - PROCESS | 1654 | 22:08:44 INFO - PROCESS | 1654 | 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) 22:08:45 INFO - PROCESS | 1654 | RSS: Main: 265248768 22:08:45 INFO - PROCESS | 1654 | 22:08:47 INFO - PROCESS | 1654 | 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) 22:08:47 INFO - PROCESS | 1654 | RSS: Main: 267702272 22:08:47 INFO - PROCESS | 1654 | 22:08:49 INFO - PROCESS | 1654 | 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) 22:08:50 INFO - PROCESS | 1654 | RSS: Main: 267849728 22:08:50 INFO - PROCESS | 1654 | 22:08:53 INFO - PROCESS | 1654 | 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) 22:08:53 INFO - PROCESS | 1654 | RSS: Main: 273936384 22:08:53 INFO - PROCESS | 1654 | 22:08:57 INFO - PROCESS | 1654 | 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) 22:08:57 INFO - PROCESS | 1654 | RSS: Main: 273338368 22:08:57 INFO - PROCESS | 1654 | 22:09:00 INFO - PROCESS | 1654 | 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) 22:09:01 INFO - PROCESS | 1654 | RSS: Main: 273780736 22:09:01 INFO - PROCESS | 1654 | 22:09:03 INFO - PROCESS | 1654 | 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) 22:09:03 INFO - PROCESS | 1654 | RSS: Main: 272207872 22:09:03 INFO - PROCESS | 1654 | 22:09:05 INFO - PROCESS | 1654 | 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) 22:09:06 INFO - PROCESS | 1654 | RSS: Main: 272261120 22:09:06 INFO - PROCESS | 1654 | 22:09:08 INFO - PROCESS | 1654 | 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) 22:09:09 INFO - PROCESS | 1654 | RSS: Main: 272842752 22:09:09 INFO - PROCESS | 1654 | 22:09:12 INFO - PROCESS | 1654 | 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) 22:09:13 INFO - PROCESS | 1654 | RSS: Main: 272576512 22:09:13 INFO - PROCESS | 1654 | 22:09:16 INFO - PROCESS | 1654 | 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) 22:09:17 INFO - PROCESS | 1654 | RSS: Main: 272949248 22:09:17 INFO - PROCESS | 1654 | 22:09:18 INFO - PROCESS | 1654 | 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) 22:09:19 INFO - PROCESS | 1654 | RSS: Main: 274767872 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | __start_tp_report 22:09:19 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 22:09:19 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 22:09:19 INFO - PROCESS | 1654 | |i|pagename|runs| 22:09:19 INFO - PROCESS | 1654 | |0;ai-astar;111;119;121;120;122;119;132;175;154;120 22:09:19 INFO - PROCESS | 1654 | |1;audio-beat-detection;145;118;112;145;117;115;115;117;132;115 22:09:19 INFO - PROCESS | 1654 | |2;audio-dft;191;208;238;272;195;195;197;197;196;194 22:09:19 INFO - PROCESS | 1654 | |3;audio-fft;82;59;61;90;53;74;75;107;85;51 22:09:19 INFO - PROCESS | 1654 | |4;audio-oscillator;94;71;86;72;73;65;66;56;63;113 22:09:19 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;92;89;92;90;93;111;136;88;93;89 22:09:19 INFO - PROCESS | 1654 | |6;imaging-darkroom;97;107;152;113;93;96;117;130;151;118 22:09:19 INFO - PROCESS | 1654 | |7;imaging-desaturate;93;139;140;94;92;109;85;84;147;147 22:09:19 INFO - PROCESS | 1654 | |8;json-parse-financial;56;63;58;57;59;60;58;61;92;83 22:09:19 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;68;70;77;77;71;69;70;75;73;72 22:09:19 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;86;90;87;91;90;100;81;109;114;110 22:09:19 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;152;153;163;156;148;159;160;173;233;172 22:09:19 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;176;177;204;245;234;185;215;240;237;176 22:09:19 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;68;71;70;70;73;71;68;70;73;71 22:09:19 INFO - PROCESS | 1654 | __end_tp_report 22:09:19 INFO - PROCESS | 1654 | __start_cc_report 22:09:19 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,963 22:09:19 INFO - PROCESS | 1654 | __end_cc_report 22:09:19 INFO - PROCESS | 1654 | __startTimestamp1480572559002__endTimestamp 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | ------- Summary: start ------- 22:09:19 INFO - PROCESS | 1654 | Number of tests: 14 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:129.30 Median:121.50 stddev:19.86 (16.3%) stddev-sans-first:19.92 22:09:19 INFO - PROCESS | 1654 | Values: 111.0 119.0 121.0 120.0 122.0 119.0 132.0 175.0 154.0 120.0 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:123.10 Median:117.50 stddev:12.73 (10.8%) stddev-sans-first:10.76 22:09:19 INFO - PROCESS | 1654 | Values: 145.0 118.0 112.0 145.0 117.0 115.0 115.0 117.0 132.0 115.0 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:208.30 Median:197.00 stddev:26.26 (13.3%) stddev-sans-first:27.09 22:09:19 INFO - PROCESS | 1654 | Values: 191.0 208.0 238.0 272.0 195.0 195.0 197.0 197.0 196.0 194.0 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:73.70 Median:74.50 stddev:17.93 (24.1%) stddev-sans-first:18.77 22:09:19 INFO - PROCESS | 1654 | Values: 82.0 59.0 61.0 90.0 53.0 74.0 75.0 107.0 85.0 51.0 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:71.50 stddev:17.10 (23.9%) stddev-sans-first:16.84 22:09:19 INFO - PROCESS | 1654 | Values: 94.0 71.0 86.0 72.0 73.0 65.0 66.0 56.0 63.0 113.0 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:97.30 Median:91.00 stddev:15.11 (16.6%) stddev-sans-first:15.91 22:09:19 INFO - PROCESS | 1654 | Values: 92.0 89.0 92.0 90.0 93.0 111.0 136.0 88.0 93.0 89.0 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:117.40 Median:151.50 stddev:21.30 (14.1%) stddev-sans-first:21.27 22:09:19 INFO - PROCESS | 1654 | Values: 97.0 107.0 152.0 113.0 93.0 96.0 117.0 130.0 151.0 118.0 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:113.00 Median:84.50 stddev:27.00 (32.0%) stddev-sans-first:27.65 22:09:19 INFO - PROCESS | 1654 | Values: 93.0 139.0 140.0 94.0 92.0 109.0 85.0 84.0 147.0 147.0 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:60.50 stddev:12.37 (20.4%) stddev-sans-first:12.71 22:09:19 INFO - PROCESS | 1654 | Values: 56.0 63.0 58.0 57.0 59.0 60.0 58.0 61.0 92.0 83.0 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:72.50 stddev:3.22 (4.4%) stddev-sans-first:3.04 22:09:19 INFO - PROCESS | 1654 | Values: 68.0 70.0 77.0 77.0 71.0 69.0 70.0 75.0 73.0 72.0 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:95.80 Median:86.50 stddev:11.58 (13.4%) stddev-sans-first:11.73 22:09:19 INFO - PROCESS | 1654 | Values: 86.0 90.0 87.0 91.0 90.0 100.0 81.0 109.0 114.0 110.0 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:166.90 Median:161.50 stddev:24.61 (15.2%) stddev-sans-first:25.50 22:09:19 INFO - PROCESS | 1654 | Values: 152.0 153.0 163.0 156.0 148.0 159.0 160.0 173.0 233.0 172.0 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:208.90 Median:224.50 stddev:28.88 (12.9%) stddev-sans-first:28.07 22:09:19 INFO - PROCESS | 1654 | Values: 176.0 177.0 204.0 245.0 234.0 185.0 215.0 240.0 237.0 176.0 22:09:19 INFO - PROCESS | 1654 | 22:09:19 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:71.00 stddev:1.72 (2.4%) stddev-sans-first:1.56 22:09:19 INFO - PROCESS | 1654 | Values: 68.0 71.0 70.0 70.0 73.0 71.0 68.0 70.0 73.0 71.0 22:09:19 INFO - PROCESS | 1654 | -------- Summary: end -------- 22:09:19 INFO - PROCESS | 1654 | 22:09:20 INFO - TEST-INFO | 1654: exit 0 22:09:20 INFO - TEST-OK | kraken | took 62538ms 22:09:20 INFO - SUITE-END | took 1178s 22:09:20 INFO - Completed test suite (00:19:38) 22:09:20 INFO - javascript benchmark 22:09:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7016.658478453591, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2705.0, 2928.8828446862126, 2973.122928736025, 3347.727863411903, 3375.139339468435, 5635.295588051494, 5685.943140568593, 5711.343195532515, 5735.913961290584, 5783.334916484606, 5603.691796951166, 5605.495505404523, 5690.914636280449, 5697.17390978309, 5716.942365662356, 4977.129002424579, 5083.389993200821, 5139.588832893378, 5144.922826157611, 5151.4075881273575, 4509.323601459774, 5033.320501732274, 5132.5124113209185, 5184.53339199472, 5225.817096401626, 4051.250518654029, 4089.0799570096788, 4195.0, 5268.710220937847, 5302.178162384817, 5112.821051263188, 5218.373795144607, 5230.92153617696, 5247.947520524822, 5272.367315922075, 4082.1835632873544, 4971.7514124294, 5162.1224391853475, 5201.323827902348, 5232.581393488512, 5483.287172667528, 5499.120140777496, 5673.177389278531, 5694.032014557535, 5725.799597014085, 6106.582013105394, 6148.78479253224, 6174.938250617482, 6189.876202475925, 6201.906971395432, 6204.13142160098, 6207.596506227081, 6209.5032397408095, 6220.066989951498, 6223.1287619733275, 5731.598788084794, 5965.343812180657, 5975.0, 5977.820665380045, 5989.3711160327975, 6209.192804935329, 6218.689065546704, 6222.937770622327, 6226.283977342664, 6265.686715664198, 6179.0422484514975, 6182.041783523561, 6214.285357183981, 6221.222347206599, 6228.968855155786, 5614.9157762633595, 5842.152887831241, 6298.779542715983, 6309.873802523924, 6326.841828954221, 6018.849528761816, 6124.014786914997, 6136.509079273647, 6149.877002459926, 6156.907646385308, 6266.717997690115, 6268.341824108494, 6283.08895210192, 6294.874102517924, 6304.211973503312, 4912.6561140719805, 4953.851384458472, 4955.182394904872, 4963.503649635008, 4969.975150124226, 4346.0, 4371.1039236956985, 4393.934090988638, 4412.271975124133, 4471.19518486669, 5693.686847223443, 5844.970775146097, 5883.441073098049, 5889.440503152279, 5895.7052147393315, 6282.968585157137, 6286.371362863677, 6295.338989406138, 6296.401841825019, 6300.684965751694, 5657.57568182388, 5688.089905615082, 5877.588568800143, 5879.735411906475, 5936.1095835625, 5380.596455265871, 5387.568994480433, 5388.299521062237, 5389.353277606712, 5401.513863752281, 5221.686698798084, 6241.469475094668, 6283.6858157091965, 6299.527535434769, 6303.968480157661, 6190.2571691397325, 6213.720382582885, 6292.1505596743855, 6300.653464059521, 6313.779017734358, 6277.905831412441, 6283.5287353449585, 6303.0, 6321.083442900662, 6321.96839015802, 5128.3076784633595, 6079.665618390854, 6197.566170368031, 6217.160683307877, 6249.375062493714, 6195.101710252078, 6226.283977342482, 6229.53278504123, 6278.309385967631, 6288.0, 4924.310596516562, 5120.513551212629, 5674.858128546819, 6174.166487524307, 6189.938100618936, 6006.819795406145, 6151.96924015377, 6154.353792851865, 6181.81454556364, 6198.473129784019, 5839.357670656309, 5892.823215303548, 5902.881942361215, 5977.6114552553945, 6000.369961154016, 5151.896962060663, 6140.995089002448, 6159.84600384994, 6167.167432396569, 6176.567640265138, 5936.821895343147, 5978.3423823378525, 6001.039833626599, 6009.489193418433, 6017.3681763414215], "value": 5623.098249366167, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.8446728112442, 468.82419092840206, 478.7127723365983, 502.5703023914553, 524.7612336386944, 37094.44358334624, 38709.41935870961, 38845.726970033276, 40147.59852401475, 40255.0, 39792.8062158135, 40073.19853602926, 40191.995200120014, 40245.79877100618, 40285.79142625726, 8331.66673333069, 9242.768930776718, 9426.0, 9444.0, 9444.244460443148, 8354.749357519284, 11058.723531911686, 11282.830757538644, 11308.660740177807, 11433.542658293658, 11287.0, 11302.943485282562, 11332.036776873976, 11337.14971377146, 11379.487923043442, 10994.175436842268, 11142.220044596881, 11155.832662510069, 11226.887731122666, 11230.719232019183, 11141.498632561554, 11192.608258710907, 11229.831552526719, 11242.550297988071, 11245.493952772063, 37990.81004594987, 38065.42901856474, 38130.85607431754, 38190.0, 38327.233455331014, 3337.9666203337897, 3348.4809854472596, 3428.451447768345, 3515.6660117288816, 3613.94579081314, 28727.0, 28953.13140605785, 29082.709172908424, 29263.829446822103, 29484.6731897063, 3151.8739250430185, 3167.5407065975305, 3179.443597370451, 3266.4610339293986, 3288.8026718396973, 402299.9999999971, 404055.9391609128, 466835.0, 467322.0, 472025.0, 44248.55751442509, 47348.053038939026, 54362.0, 54828.72585637126, 54996.175057374174, 50024.74987625112, 56080.0, 56141.0, 56160.43839561634, 56494.717526412926, 14873.884458665427, 15086.622834429008, 15169.0, 15366.615834604225, 15370.0, 10776.0, 11030.724231894106, 11838.052955763518, 11892.940535297268, 11978.940105299593, 14236.715265694627, 14620.976531642684, 14646.340914658866, 14652.55370664058, 14771.704565908622, 5536.916946245809, 6469.482441404676, 6511.804645860537, 6528.51036172289, 6595.307492713292, 853.6585365853584, 877.644553955649, 890.1899271662755, 929.6142101028133, 976.6630512473185, 27332.180034598994, 28407.857960710066, 28580.142595721747, 28841.27896802555, 28954.27614309617, 859.7420773767844, 974.9951250243703, 1007.1640538353149, 1051.7370657335666, 1079.3416016230094, 13451.32743362828, 14653.560393188221, 14753.40986360555, 14979.951403401876, 15185.0, 448241.0, 453841.0, 454976.0, 455956.0, 456238.0, 50795.0, 51229.975400491785, 51296.48703512992, 51340.716482088246, 51371.48628513667, 1146.8165093585153, 1179.4751335655722, 1313.507434711983, 1320.8348956380453, 1340.336533415946, 26899.596506052032, 27484.450310994067, 28162.71837281601, 28178.0, 28221.858890706237, 43281.3507797377, 43986.0, 44269.33595995998, 44298.89252768578, 44540.886477838314, 411299.0, 413504.0, 413538.0, 414297.0, 416177.0, 35783.92648220558, 36264.0, 36680.98254595916, 36774.26451471009, 36786.0, 41379.58620413878, 41804.790976044926, 42461.363079553215, 42694.0, 43094.0, 33017.17457063593, 34431.655683443845, 34557.82721086378, 35364.469532956515, 35384.0, 29320.560191596716, 34990.47514287339, 35326.76356327516, 35864.82067589645, 36027.27945441129, 34517.0, 35186.648133518334, 35214.11964700903, 35450.82274588611, 35581.466278006366, 29954.85022574873, 30123.096307110824, 31704.0, 31860.0, 32001.6799832008, 23777.0, 23913.880430597736, 23975.640365394185, 24311.75688243095, 24340.75659243385], "value": 18770.427381599984, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.24067986011687, 365.30774182923346, 369.79476390603213, 373.60024773492364, 380.69924759440045, 17955.832870863385, 19234.422967310984, 19713.507162320948, 20275.797242027595, 20510.84636113209, 17788.46634600964, 19698.70451943222, 19839.10724017419, 20182.495437614067, 20249.898750506225, 5545.833624991246, 5618.297712785902, 5826.533877289807, 5828.795992140276, 5840.853978650525, 5724.656520608777, 5883.382244864292, 5884.882302353951, 5889.322727886305, 5900.321463031742, 5593.300837395325, 5816.418358164192, 5824.767009319622, 5826.980278451275, 5845.123231515286, 5740.138979153118, 5779.335376431701, 5782.971085144568, 5849.824505264827, 5858.0, 4990.026944745776, 5005.6996580205305, 5009.2486127080865, 5278.709670968095, 5295.232191332274, 12818.935905320506, 13011.804822927663, 13023.934880325538, 13754.0, 24076.0, 1803.7023891057959, 2084.4396579533854, 2119.671450925109, 2304.8271379646426, 2769.9307517312227, 9871.0, 10215.591376344937, 10243.87317395086, 12269.631911042683, 14461.421543138262, 1642.958926026847, 1778.0931724820305, 1786.6516029374277, 1851.5371157210698, 1861.1903821837543, 91202.0, 106285.40571891428, 114065.42967285194, 118176.0, 120643.0, 10428.322159059637, 11392.658220253406, 11601.955823975883, 11649.301041937511, 11756.647300580997, 14317.64205894861, 14453.132812031312, 14454.783178252335, 14471.565853024425, 14481.20353380553, 8133.024037115584, 8290.336773058141, 8320.583970801435, 8338.560780807868, 8453.027901791373, 6798.810208213543, 7838.059432867978, 7983.481073730189, 8011.158828322942, 8242.876356854533, 3750.887473375803, 3877.0, 4130.834766609362, 4477.813379454447, 4539.341795439643, 1893.280553389703, 1955.8044195580328, 1956.3544030469911, 1957.2366776956999, 1957.784643689193, 838.5471845203643, 926.9026752191057, 943.6272672294406, 965.4931161140457, 970.2335155227291, 19848.60302793965, 20335.694964575545, 21572.0, 21680.783192168194, 22435.77564224337, 857.2070834478083, 858.8277001892372, 882.1575395497284, 916.5966974531185, 1018.7351288665001, 9064.637414503479, 10741.462926853626, 11203.887961120448, 11220.270682405617, 11301.0, 562023.0, 568582.0, 581203.0, 590637.0, 593317.0, 110243.44878275557, 120828.0, 126503.0, 137523.0, 138762.0, 931.5994122527243, 972.601233494264, 1009.3338396658318, 1017.8880323164447, 1018.8166130096654, 18042.45872623771, 18809.247630094644, 18881.71677424867, 18902.7164592534, 18936.810631893506, 34075.488867667504, 41701.0, 44413.555864442234, 53719.194212087634, 53899.0, 56088.0, 76991.0, 77022.0, 77884.61057694675, 78245.82631260421, 7759.0, 7942.364610831004, 7964.9203507966495, 8119.918800812153, 8186.54973976437, 9870.802583948138, 12243.449044793184, 12320.507179712711, 12599.496020159091, 12633.873661263637, 8577.614007369559, 10681.0, 10854.511546980562, 11100.833487497532, 11123.83199764013, 10612.522436490222, 10715.678529644123, 10806.459677016275, 11055.557777689124, 11106.222564420414, 9235.399699019543, 10750.408727520062, 11013.449327533752, 11158.386288754196, 11387.54449822031, 7941.722039728813, 7978.281954624112, 8157.0, 8163.551004694798, 8190.058143313357, 10243.7439064024, 10417.531211095367, 10714.785704286027, 10918.672439826818, 11038.337699738042], "value": 9178.9351565647, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1313.619050475362, 1450.75235297644, 1608.7345587977984, 1786.1962117047333, 1789.418439007323, 7537.962310188442, 7803.882941755878, 8130.51216926984, 8537.957310213447, 8538.060813310538, 8021.518708877456, 8305.91694083059, 8332.291755200815, 8458.281046111088, 8468.66125354985, 2281.9885900570475, 2323.779240972105, 2331.428799944016, 2334.241371554243, 2338.6959695239593, 2234.273860995175, 2238.87686177261, 2239.731232252132, 2243.596152692515, 2350.4828937633692, 2302.7466978632337, 2305.3890718959487, 2316.3977365884907, 2323.5120624668757, 2325.93022209334, 1886.962260754791, 2221.522372689871, 2247.438140464884, 2251.2458326460655, 2253.9436514087115, 2021.82814460771, 2032.9796702032938, 2062.4946888012532, 2135.2099723102397, 2297.85063970843, 8290.08809031006, 8345.916540834574, 8369.0, 8518.87221691675, 8520.40357174998, 4487.461504619434, 5097.668651537638, 5256.053910296145, 5270.973645131749, 5277.129273669878, 3233.8383080845865, 3276.737860971117, 3492.8602855885733, 3572.8570857165682, 3601.7478776485655, 1038.7974345002729, 1067.759754055339, 1075.8386242063675, 1088.2491081153978, 1097.3415950429758, 10882.782344353147, 10889.01998820102, 10959.616413425492, 11010.229283950126, 11025.724356891062, 4477.619402350771, 4608.124456353282, 4672.486026537078, 4687.320338550891, 4707.976460117678, 9371.578278977462, 9476.289278304017, 9487.620495180116, 9494.810103797885, 9498.192653624381, 5196.844094677166, 5200.609954253446, 5210.817621383233, 5213.322268105122, 5216.973915130476, 4253.21315556623, 4278.507971583245, 4699.295105734099, 4760.500147484534, 4761.761911904391, 3469.7397695172963, 3588.1567831559564, 3762.5861155272896, 3841.0, 3875.224955008953, 1256.0454054918328, 1256.4785613970282, 1264.6395777203452, 1266.468083404972, 1278.1691900264902, 763.4541302968404, 860.9096044915319, 902.201551626815, 903.3179949138358, 905.8867641544807, 5582.553395728332, 5697.6296540724725, 5726.3700992890745, 5793.681347525927, 5864.853378665567, 418.4288446270879, 430.22129944800014, 432.61065041462933, 434.50032462668185, 434.8369361489441, 3648.7445878788762, 3729.1589662647516, 3795.7912314822397, 3869.3228684980018, 3924.4113382992778, 9653.420794752337, 9765.755856103513, 9838.754031149136, 9955.751106222402, 9985.251106167067, 9445.433274003582, 9707.854382184272, 9807.26445516589, 9973.950130249303, 10083.49582520871, 7751.612419379121, 7894.328982036591, 7907.525548467111, 7970.963774709251, 7974.242446967645, 4755.738434386142, 5740.741666625096, 5830.4461076197695, 5852.707364631666, 5905.852353691193, 9160.954195228982, 9443.716688499357, 9449.905500945177, 9461.432314061178, 9471.905280947378, 7367.594782286811, 7943.563104029334, 9177.567944494847, 9603.759906002126, 9643.08390702882, 6554.737810487718, 6768.7292508299115, 6966.12923384577, 7120.893186602101, 7304.707811687473, 7819.0, 7967.964164658557, 8034.879476807736, 8071.0, 8114.634841432267, 7071.328223818729, 7125.0, 7213.0, 7257.0565826442225, 7298.927010729824, 6690.663981094737, 6818.659067046528, 6824.590524568542, 6863.176418829772, 6998.2651821558, 6434.806955791334, 6445.548811583144, 6490.221173459215, 6497.180789378982, 6510.121133646897, 4819.421669399694, 4842.661013729005, 4871.171900776805, 4889.731064791471, 4979.0788704089855, 4774.1883879739835, 4785.0, 4875.585575226146, 4946.9364086721525, 4960.627952903475], "value": 4293.573941281226, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.6621534558276, 301.1732793481892, 307.86915560886615, 309.66865453964266, 311.5342562868511, 5252.842414727555, 5266.52601265886, 5362.0, 5465.836024919254, 5504.908134908734, 5179.378474583046, 5307.73461326934, 5355.223492593572, 5362.329708786402, 5371.57027437805, 3503.597086335066, 3848.2688289224957, 4131.958680413202, 4143.627073563379, 4145.274576949037, 3262.9021129366038, 3757.3612485877466, 4158.4178215049915, 4166.166766646667, 4168.415162959852, 3316.5522654441584, 3451.2924850405734, 3735.2529494101154, 4033.7781422021776, 4052.527957945651, 3319.9170020749552, 3799.601041890603, 4015.638592526687, 4030.254402935437, 4041.3937909313836, 3486.6513348665185, 3507.158282012324, 3510.5260789793306, 3513.489199644152, 3536.9469457958153, 4978.975105124451, 5157.6647517911215, 5212.061828870802, 5287.682739035632, 5313.760880760375, 3607.881556717426, 4503.770633536387, 4597.632189424838, 4597.95402045982, 4638.095571363586, 5081.949180508184, 5185.662931909414, 5634.661920284796, 5659.320881494205, 5676.3188417389765, 3114.5951026366656, 3155.9684403156134, 3384.3569721752788, 3845.0393197237972, 3856.151646637735, 4873.513578358599, 5714.228579141804, 5880.5883588148845, 5889.411058894077, 5941.544321641182, 3415.624281329052, 3625.0, 3899.512560929884, 3921.8039098044983, 3923.705722070856, 3644.5444319460066, 3951.78265195417, 3976.522817261889, 3987.1228329767405, 4001.0, 3838.5393752749846, 4011.6590089842107, 4305.9569404306185, 4318.762468064287, 4614.74618895964, 4733.502982186889, 5717.171010203498, 5727.914081288784, 5731.914021289684, 5732.082866741302, 1033.6278939581744, 1229.4079167478137, 1241.3358853013774, 1281.8591453606298, 1286.9613911582667, 751.0124186694479, 757.458417231671, 869.2437579306044, 882.43965082172, 884.3455842676502, 4865.905171336421, 4911.263310503453, 5083.822066227664, 5103.897922041539, 5122.718250496258, 4812.783424745825, 4830.140395947586, 4995.425526064477, 5626.268585083994, 5655.406182350876, 5158.807506809666, 5371.973140134352, 5412.806444197863, 5416.2688037114485, 5432.70120143388, 2642.748938850806, 2729.713380095101, 2803.046964032198, 2899.6375453068367, 2927.2974486123603, 5288.576913846883, 5584.4921871094575, 5624.3250809903075, 5631.915521267184, 5639.943600564024, 4504.6396288296855, 4946.950530494721, 5135.794568217229, 5248.921266181011, 5374.54316383104, 4847.781849816767, 5458.317710286215, 5678.387941546935, 5718.942810571924, 5855.443732845373, 4663.976680116578, 5200.869978250574, 5454.318210223722, 5479.342478902558, 5585.245991791056, 5606.411326810626, 5839.693321138042, 5878.265216847894, 5884.293884733859, 5905.734241959208, 5239.318888544465, 5359.68687671523, 5426.403095659553, 6083.908741368795, 6093.745314233762, 3413.7951722896705, 3847.2882516734494, 3931.9017024574614, 3944.2505923874373, 3948.1906209227614, 5026.899462010812, 5566.385748133158, 5920.585559010828, 5926.614770040106, 5967.358976281557, 4906.582940450102, 5349.705766182898, 5704.0, 5750.25246717924, 5778.768849246151, 4808.903821923612, 5755.8561035974435, 5759.337676167127, 5771.480566749013, 5790.783935373619, 5397.622166448468, 5437.755301011385, 5534.778608855761, 5651.745671444877, 5658.575606829587, 4355.756785255532, 5255.421903590678, 5272.235525848807, 5551.8889622206525, 5771.480566749013, 4898.926516102332, 5303.920441193308, 5315.255864178941, 5531.280933478622, 5614.578906581941], "value": 4028.967505841383, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.4055201698513, 693.1716598664592, 707.5542408282781, 756.8145804277952, 797.4298376660688, 13303.401346939387, 14671.779923301146, 15036.473723419682, 15390.769138462918, 15501.06993580386, 14121.43514259431, 14231.07498012631, 14246.358913848875, 14288.714225715505, 14298.571042868705, 799.3884678221166, 808.4017826808164, 819.6557445872718, 820.5240960243075, 821.4250024982506, 784.2157842157842, 817.4073796497552, 818.7298191596789, 820.4175035724643, 824.7938015496126, 853.4324674091722, 858.7938894665267, 864.2351518905782, 864.9610767515445, 868.3443999780158, 841.6717480182733, 857.5669287010082, 863.1627321498169, 863.1756672377865, 864.8918885139358, 640.1933563709713, 640.2317219336768, 641.1216633212529, 641.3009819296942, 641.8234985379012, 14120.435182592684, 14755.77866332006, 14932.62668433287, 14959.925200374037, 14978.251087445693, 11416.600418985296, 13519.188848669111, 13825.512700654439, 13938.860611393859, 14101.730844223966, 11682.24065435744, 11884.346360950145, 11983.921447069719, 12172.026237901035, 12357.443915023845, 3114.4549703801745, 3360.428727116396, 3728.3848165053005, 3787.1668232988973, 3869.8839034829, 80911.59544202242, 99630.0, 100609.0, 100769.0, 113129.0, 9072.954635226914, 9414.070115626417, 9636.75908102303, 9931.702048938543, 10263.897361026444, 17296.481105566854, 21348.252811151535, 21369.465763356042, 21444.892775536024, 21473.67789483159, 7311.75700130979, 7425.9628701856145, 7750.806229844186, 7758.5004880186625, 7897.368210543143, 3674.9632503675157, 4278.957210427918, 4375.299952007664, 4393.692441529126, 4418.624416924597, 3453.827308634558, 3574.910627234288, 3579.8926032219074, 3581.4985901973746, 3589.892303230855, 603.7796204385368, 686.8385929306609, 691.3881215124551, 692.4460431654657, 709.521073275537, 556.5224329405435, 587.8794847056344, 597.8744463662592, 628.3779058731914, 643.4080645805869, 9181.35730498872, 9253.398529095588, 9313.53432328381, 9440.90559094414, 9525.761855953657, 535.611681530887, 593.964362138273, 605.5458406195354, 627.1188979483833, 656.4157899469476, 3277.705006549422, 3967.26605577969, 4253.766042867672, 4448.933266001012, 4530.139273538017, 81854.0, 104535.0, 106997.9300206988, 109349.0, 112841.0, 94104.0, 95149.0, 95545.52227238819, 96418.0, 97950.34844076524, 71327.64336178286, 74617.0, 75393.62303188558, 75703.62148189223, 77332.22667773363, 8387.266613354106, 8783.516906569945, 8820.0, 8832.911670883466, 9012.0, 46987.0, 48996.02007959892, 49745.502544974086, 50533.74733126311, 50712.47862564129, 62441.43896402626, 62855.0, 65164.34835651583, 76548.0, 76712.84930726155, 11477.426128693365, 11532.59635912739, 11789.292642441482, 11868.347240901834, 12039.578614748441, 12610.936945315214, 12794.488220471078, 13004.739905202032, 13023.297419790564, 13095.148815326973, 11801.17391782567, 12061.577844775391, 12148.878511215129, 12531.18547294423, 12585.496580136693, 12074.879251207376, 12161.574344897886, 12394.81407778902, 12432.81350779721, 12449.93775031119, 10327.793444131226, 11306.925842044993, 11381.54473821038, 11663.475143618387, 11748.647540573797, 9224.446533208029, 9460.101290377403, 9895.604175833174, 10107.090361867467, 10108.039736225046, 10017.998200179924, 10147.543360548942, 10152.898471015491, 10160.390376577428, 10169.983001699771], "value": 7120.015563926491, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1617.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 119.0, 121.0, 120.0, 122.0, 119.0, 132.0, 175.0, 154.0, 120.0], "value": 129.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 118.0, 112.0, 145.0, 117.0, 115.0, 115.0, 117.0, 132.0, 115.0], "value": 123.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 208.0, 238.0, 272.0, 195.0, 195.0, 197.0, 197.0, 196.0, 194.0], "value": 208.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 61.0, 90.0, 53.0, 74.0, 75.0, 107.0, 85.0, 51.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 71.0, 86.0, 72.0, 73.0, 65.0, 66.0, 56.0, 63.0, 113.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 92.0, 90.0, 93.0, 111.0, 136.0, 88.0, 93.0, 89.0], "value": 97.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 107.0, 152.0, 113.0, 93.0, 96.0, 117.0, 130.0, 151.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 139.0, 140.0, 94.0, 92.0, 109.0, 85.0, 84.0, 147.0, 147.0], "value": 113.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 58.0, 57.0, 59.0, 60.0, 58.0, 61.0, 92.0, 83.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 77.0, 77.0, 71.0, 69.0, 70.0, 75.0, 73.0, 72.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 87.0, 91.0, 90.0, 100.0, 81.0, 109.0, 114.0, 110.0], "value": 95.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 163.0, 156.0, 148.0, 159.0, 160.0, 173.0, 233.0, 172.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 177.0, 204.0, 245.0, 234.0, 185.0, 215.0, 240.0, 237.0, 176.0], "value": 208.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 70.0, 70.0, 73.0, 71.0, 68.0, 70.0, 73.0, 71.0], "value": 70.5, "unit": "score"}]}]} 22:09:20 INFO - Return code: 0 22:09:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:09:20 ERROR - # TBPL SUCCESS # 22:09:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:09:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:09:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:09:20 INFO - 2016-11-30 22:09:20.343 screenresolution[1659:10993] starting screenresolution argv=screenresolution get 22:09:20 INFO - 2016-11-30 22:09:20.347 screenresolution[1659:10993] Display 0: 1600x1200x32@60 22:09:20 INFO - 2016-11-30 22:09:20.353 screenresolution[1660:10996] starting screenresolution argv=screenresolution list 22:09:20 INFO - Available Modes on Display 0 22:09:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:09:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:09:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:09:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:09:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:09:20 INFO - Intel Iris: 22:09:20 INFO - Chipset Model: Intel Iris 22:09:20 INFO - Type: GPU 22:09:20 INFO - Bus: Built-In 22:09:20 INFO - VRAM (Dynamic, Max): 1536 MB 22:09:20 INFO - Vendor: Intel (0x8086) 22:09:20 INFO - Device ID: 0x0a2e 22:09:20 INFO - Revision ID: 0x0009 22:09:20 INFO - Displays: 22:09:20 INFO - Display: 22:09:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:09:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:09:20 INFO - Display Serial Number: 1600x1200 60 22:09:20 INFO - Main Display: Yes 22:09:20 INFO - Mirror: Off 22:09:20 INFO - Online: Yes 22:09:20 INFO - Rotation: Supported 22:09:20 INFO - Return code: 0 22:09:20 INFO - Running post-action listener: _resource_record_post_action 22:09:20 INFO - [mozharness: 2016-12-01 06:09:20.600205Z] Finished run-tests step (success) 22:09:20 INFO - Running post-run listener: _resource_record_post_run 22:09:20 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38558208; Write bytes: 692253184; Read time: 248; Write time: 1990 22:09:20 INFO - TinderboxPrint: CPU usage
54.5% 22:09:20 INFO - TinderboxPrint: I/O read bytes / time
38,558,208 / 248 22:09:20 INFO - TinderboxPrint: I/O write bytes / time
692,253,184 / 1,990 22:09:20 INFO - TinderboxPrint: CPU idle
2,208.8 (45.5%) 22:09:20 INFO - TinderboxPrint: CPU system
979.2 (20.2%) 22:09:20 INFO - TinderboxPrint: CPU user
1,661.6 (34.3%) 22:09:20 INFO - TinderboxPrint: Swap in / out
422,252,544 / 0 22:09:20 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 378841600; Write bytes: 372791296; Read time: 28767; Write time: 776 22:09:20 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 36432384; Write bytes: 305089024; Read time: 227; Write time: 1174 22:09:21 INFO - Running post-run listener: _upload_blobber_files 22:09:21 INFO - Blob upload gear active. 22:09:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:09:21 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 22:09:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:09:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:09:21 INFO - (blobuploader) - INFO - Open directory for files ... 22:09:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:09:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:09:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:22 INFO - (blobuploader) - INFO - Done attempting. 22:09:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:09:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:09:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:24 INFO - (blobuploader) - INFO - Done attempting. 22:09:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:09:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:09:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:28 INFO - (blobuploader) - INFO - Done attempting. 22:09:28 INFO - (blobuploader) - INFO - Iteration through files over. 22:09:28 INFO - Return code: 0 22:09:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:09:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:09:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c8f39952ece52cc51c0cdc7f9929a64afc8123abccd1447bdf44e53331d9a6448279a9ee3809ef2de65cabd8561fbe6b764df6cddada149999fd1c6014e859e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f82acaeff77f868084e5d43c49c58c3f72fc65308bf6329bd2479040f4945253892544cfd7253a9033229e12008ea55d289dce809760bd1e60dd58daa278a5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/140fca2dda22d9fee4ca80dce230a3fc01fe14922038bcb04c79c3a0430890a32316fe7d9b0c97af572116ed3216f3938aecb8c0e43da99b8fa9e65f4e5dd53c"} 22:09:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:09:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:09:28 INFO - Contents: 22:09:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c8f39952ece52cc51c0cdc7f9929a64afc8123abccd1447bdf44e53331d9a6448279a9ee3809ef2de65cabd8561fbe6b764df6cddada149999fd1c6014e859e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f82acaeff77f868084e5d43c49c58c3f72fc65308bf6329bd2479040f4945253892544cfd7253a9033229e12008ea55d289dce809760bd1e60dd58daa278a5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/140fca2dda22d9fee4ca80dce230a3fc01fe14922038bcb04c79c3a0430890a32316fe7d9b0c97af572116ed3216f3938aecb8c0e43da99b8fa9e65f4e5dd53c"} 22:09:28 INFO - Running post-run listener: copy_logs_to_upload_dir 22:09:28 INFO - Copying logs to upload dir... 22:09:28 INFO - mkdir: /builds/slave/test/build/upload/logs 22:09:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.088106 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-30 22:09:28.142689) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 22:09:28.150618) ========= 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.uq8EnFstTC/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.RiNayVu7w0/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c8f39952ece52cc51c0cdc7f9929a64afc8123abccd1447bdf44e53331d9a6448279a9ee3809ef2de65cabd8561fbe6b764df6cddada149999fd1c6014e859e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f82acaeff77f868084e5d43c49c58c3f72fc65308bf6329bd2479040f4945253892544cfd7253a9033229e12008ea55d289dce809760bd1e60dd58daa278a5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/140fca2dda22d9fee4ca80dce230a3fc01fe14922038bcb04c79c3a0430890a32316fe7d9b0c97af572116ed3216f3938aecb8c0e43da99b8fa9e65f4e5dd53c"} build_url:https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010831 build_url: 'https://queue.taskcluster.net/v1/task/blPS-9JrRHmihaq1QVjNzQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c8f39952ece52cc51c0cdc7f9929a64afc8123abccd1447bdf44e53331d9a6448279a9ee3809ef2de65cabd8561fbe6b764df6cddada149999fd1c6014e859e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f82acaeff77f868084e5d43c49c58c3f72fc65308bf6329bd2479040f4945253892544cfd7253a9033229e12008ea55d289dce809760bd1e60dd58daa278a5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/140fca2dda22d9fee4ca80dce230a3fc01fe14922038bcb04c79c3a0430890a32316fe7d9b0c97af572116ed3216f3938aecb8c0e43da99b8fa9e65f4e5dd53c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 22:09:28.189729) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 22:09:28.190061) ========= 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.uq8EnFstTC/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.RiNayVu7w0/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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.004578 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 22:09:28.237782) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 22:09:28.238092) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 22:09:28.241401) ========= ========= Total master_lag: 0.21 =========