builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0002 starttime: 1482573959.49 results: success (0) buildid: 20161224004004 builduid: 5cce009b48fc49dbaed57b4ea4656770 revision: ec2672807887aa46bb4c2966478afa08d610d72d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-24 02:05:59.492513) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-24 02:05:59.493556) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-24 02:05:59.494246) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-24 02:05:59.844855) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 02:05:59.845360) ========= 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.o9epAFFiIK/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.gXfmfiRnh3/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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-12-24 02:05:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 164M=0s 2016-12-24 02:06:00 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.480681 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 02:06:00.394934) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 02:06:00.395672) ========= 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.o9epAFFiIK/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.gXfmfiRnh3/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.028479 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 02:06:00.448066) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-24 02:06:00.448829) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ec2672807887aa46bb4c2966478afa08d610d72d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ec2672807887aa46bb4c2966478afa08d610d72d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.o9epAFFiIK/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.gXfmfiRnh3/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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-12-24 02:06:00,549 truncating revision to first 12 chars 2016-12-24 02:06:00,549 Setting DEBUG logging. 2016-12-24 02:06:00,549 attempt 1/10 2016-12-24 02:06:00,549 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ec2672807887?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-24 02:06:01,582 unpacking tar archive at: mozilla-aurora-ec2672807887/testing/mozharness/ program finished with exit code 0 elapsedTime=1.307508 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-24 02:06:01.789028) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-24 02:06:01.789724) ========= script_repo_revision: ec2672807887aa46bb4c2966478afa08d610d72d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-24 02:06:01.790799) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-24 02:06:01.791566) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-24 02:06:01.813486) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-24 02:06:01.814149) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.o9epAFFiIK/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.gXfmfiRnh3/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:06:02 INFO - MultiFileLogger online at 20161224 02:06:02 in /builds/slave/test 02:06:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:06:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:06:02 INFO - {'append_to_log': False, 02:06:02 INFO - 'base_work_dir': '/builds/slave/test', 02:06:02 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:06:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:06:02 INFO - 'branch': 'Mozilla-Aurora', 02:06:02 INFO - 'buildbot_json_path': 'buildprops.json', 02:06:02 INFO - 'config_files': ('talos/mac_config.py',), 02:06:02 INFO - 'default_actions': ('clobber', 02:06:02 INFO - 'read-buildbot-config', 02:06:02 INFO - 'download-and-extract', 02:06:02 INFO - 'populate-webroot', 02:06:02 INFO - 'create-virtualenv', 02:06:02 INFO - 'install', 02:06:02 INFO - 'run-tests'), 02:06:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:06:02 INFO - 'download_minidump_stackwalk': True, 02:06:02 INFO - 'download_symbols': 'ondemand', 02:06:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:06:02 INFO - 'tooltool.py': '/tools/tooltool.py', 02:06:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:06:02 INFO - '/tools/misc-python/virtualenv.py')}, 02:06:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:06:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:06:02 INFO - 'installer_path': 'installer.exe', 02:06:02 INFO - 'log_level': 'info', 02:06:02 INFO - 'log_name': 'talos', 02:06:02 INFO - 'log_to_console': True, 02:06:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:06:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:06:02 INFO - 'opt_config_files': (), 02:06:02 INFO - 'pip_index': False, 02:06:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:06:02 INFO - 'cmd': ('bash', 02:06:02 INFO - '-c', 02:06:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:06:02 INFO - 'enabled': True, 02:06:02 INFO - 'halt_on_failure': False, 02:06:02 INFO - 'name': 'check_screen_resolution'},), 02:06:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:06:02 INFO - 'cmd': ('bash', 02:06:02 INFO - '-c', 02:06:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:06:02 INFO - 'enabled': True, 02:06:02 INFO - 'halt_on_failure': False, 02:06:02 INFO - 'name': 'check_screen_resolution'},), 02:06:02 INFO - 'run_cmd_checks_enabled': True, 02:06:02 INFO - 'sps_profile': False, 02:06:02 INFO - 'sps_profile_interval': 0, 02:06:02 INFO - 'suite': 'dromaeojs-e10s', 02:06:02 INFO - 'system_bits': '32', 02:06:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:06:02 INFO - 'title': 't-yosemite-r7-0002', 02:06:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:06:02 INFO - 'use_talos_json': True, 02:06:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:06:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:06:02 INFO - 'work_dir': 'build'} 02:06:02 INFO - [mozharness: 2016-12-24 10:06:02.019591Z] Running clobber step. 02:06:02 INFO - Running pre-action listener: _resource_record_pre_action 02:06:02 INFO - Running main action method: clobber 02:06:02 INFO - rmtree: /builds/slave/test/build 02:06:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:06:04 INFO - Running post-action listener: _resource_record_post_action 02:06:04 INFO - [mozharness: 2016-12-24 10:06:04.196230Z] Finished clobber step (success) 02:06:04 INFO - [mozharness: 2016-12-24 10:06:04.196342Z] Running read-buildbot-config step. 02:06:04 INFO - Running pre-action listener: _resource_record_pre_action 02:06:04 INFO - Running main action method: read_buildbot_config 02:06:04 INFO - Using buildbot properties: 02:06:04 INFO - { 02:06:04 INFO - "project": "", 02:06:04 INFO - "product": "firefox", 02:06:04 INFO - "script_repo_revision": "production", 02:06:04 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:06:04 INFO - "repository": "", 02:06:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:06:04 INFO - "buildid": "20161224004004", 02:06:04 INFO - "stage_platform": "macosx64", 02:06:04 INFO - "basedir": "/builds/slave/test", 02:06:04 INFO - "nightly_build": "True", 02:06:04 INFO - "buildnumber": 2, 02:06:04 INFO - "slavename": "t-yosemite-r7-0002", 02:06:04 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:06:04 INFO - "platform": "yosemite_r7", 02:06:04 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:06:04 INFO - "branch": "mozilla-aurora", 02:06:04 INFO - "repo_path": "releases/mozilla-aurora", 02:06:04 INFO - "revision": "ec2672807887aa46bb4c2966478afa08d610d72d", 02:06:04 INFO - "pgo_build": "False", 02:06:04 INFO - "builduid": "5cce009b48fc49dbaed57b4ea4656770", 02:06:04 INFO - "slavebuilddir": "test" 02:06:04 INFO - } 02:06:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:06:04 INFO - Running post-action listener: _resource_record_post_action 02:06:04 INFO - [mozharness: 2016-12-24 10:06:04.199801Z] Finished read-buildbot-config step (success) 02:06:04 INFO - [mozharness: 2016-12-24 10:06:04.199913Z] Running download-and-extract step. 02:06:04 INFO - Running pre-action listener: _resource_record_pre_action 02:06:04 INFO - Running main action method: download_and_extract 02:06:04 INFO - mkdir: /builds/slave/test/build/tests 02:06:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:04 INFO - trying https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:06:04 INFO - Downloading https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:06:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:06:05 INFO - Downloaded 1199 bytes. 02:06:05 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:06:05 INFO - Using the following test package requirements: 02:06:05 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:06:05 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:05 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:06:05 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:05 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:06:05 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:05 INFO - u'jsshell-mac.zip'], 02:06:05 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:05 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:06:05 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:06:05 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:05 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:06:05 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:05 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:06:05 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:05 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:06:05 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:05 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:06:05 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:06:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:06:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:06:05 INFO - Fetch https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:06:06 INFO - Http code: 200 02:06:06 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 02:06:06 INFO - x-amz-cf-id: KyUT3O9W8yihjirFJ_A9Zkcjq4yno-RuiUvsitxnwewKUoCQ0ltr5w== 02:06:06 INFO - x-amz-version-id: _16RJcblC.VTPXhepbOypzamBCmpbHNn 02:06:06 INFO - Expected file size: 16271191 02:06:06 INFO - Obtained file size: 16271191 02:06:13 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:06:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:06:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:06:13 INFO - Fetch https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:06:13 INFO - Http code: 200 02:06:13 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 02:06:13 INFO - x-amz-cf-id: EpKdRFB8NWmD6Ebq7hpS0FJWXaLglpb7CuovsnhvH-1nLPYiQqLnUA== 02:06:13 INFO - x-amz-version-id: _16RJcblC.VTPXhepbOypzamBCmpbHNn 02:06:13 INFO - Expected file size: 16271191 02:06:13 INFO - Obtained file size: 16271191 02:06:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:06:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:06:20 INFO - Fetch https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:06:21 INFO - Http code: 200 02:06:21 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 02:06:21 INFO - x-amz-cf-id: 1YQVysdMUH3kXpM14IMXI9smhChYarF1k_sf7B1x0f_8oJvUo9BVfA== 02:06:21 INFO - x-amz-version-id: Nr4Zt5LApcVjelhtAprUh3CZpyUnwvqA 02:06:21 INFO - Expected file size: 14409038 02:06:21 INFO - Obtained file size: 14409038 02:06:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:22 INFO - trying https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:06:22 INFO - Downloading https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:06:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:06:24 INFO - Downloaded 109903360 bytes. 02:06:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:06:24 INFO - mkdir: /builds/slave/test/properties 02:06:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:06:24 INFO - Writing to file /builds/slave/test/properties/build_url 02:06:24 INFO - Contents: 02:06:24 INFO - build_url:https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:06:25 INFO - Running post-action listener: _resource_record_post_action 02:06:25 INFO - Running post-action listener: set_extra_try_arguments 02:06:25 INFO - [mozharness: 2016-12-24 10:06:25.498336Z] Finished download-and-extract step (success) 02:06:25 INFO - [mozharness: 2016-12-24 10:06:25.498456Z] Running populate-webroot step. 02:06:25 INFO - Running pre-action listener: _resource_record_pre_action 02:06:25 INFO - Running main action method: populate_webroot 02:06:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:06:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:06:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:06:25 INFO - '--tppagecycles', 02:06:25 INFO - '7'], 02:06:25 INFO - 'tests': ['tsvgm']}}, 02:06:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:06:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:06:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:06:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:06:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:06:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:06:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:25 INFO - 'talos_options': ['--disable-e10s'], 02:06:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:06:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:06:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:25 INFO - 'talos_options': ['--disable-e10s'], 02:06:25 INFO - 'tests': ['damp', 'tps']}, 02:06:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:25 INFO - 'tests': ['damp', 'tps']}, 02:06:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:06:25 INFO - 'tests': ['dromaeo_dom']}, 02:06:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:06:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:06:25 INFO - 'tests': ['basic_compositor_video']}, 02:06:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:06:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:06:25 INFO - 'tests': ['a11yr', 02:06:25 INFO - 'ts_paint', 02:06:25 INFO - 'tpaint', 02:06:25 INFO - 'sessionrestore', 02:06:25 INFO - 'sessionrestore_no_auto_restore', 02:06:25 INFO - 'tabpaint']}, 02:06:25 INFO - 'other-e10s': {'tests': ['a11yr', 02:06:25 INFO - 'ts_paint', 02:06:25 INFO - 'tpaint', 02:06:25 INFO - 'sessionrestore', 02:06:25 INFO - 'sessionrestore_no_auto_restore', 02:06:25 INFO - 'tabpaint']}, 02:06:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:06:25 INFO - 'ts_paint', 02:06:25 INFO - 'tpaint', 02:06:25 INFO - 'sessionrestore', 02:06:25 INFO - 'sessionrestore_no_auto_restore', 02:06:25 INFO - 'tabpaint']}, 02:06:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:06:25 INFO - 'ts_paint', 02:06:25 INFO - 'tpaint', 02:06:25 INFO - 'sessionrestore', 02:06:25 INFO - 'sessionrestore_no_auto_restore', 02:06:25 INFO - 'tabpaint']}, 02:06:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:06:25 INFO - 'tests': ['a11yr', 02:06:25 INFO - 'ts_paint', 02:06:25 INFO - 'tpaint', 02:06:25 INFO - 'sessionrestore', 02:06:25 INFO - 'sessionrestore_no_auto_restore', 02:06:25 INFO - 'tabpaint']}, 02:06:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:06:25 INFO - 'tests': ['a11yr', 02:06:25 INFO - 'ts_paint', 02:06:25 INFO - 'tpaint', 02:06:25 INFO - 'sessionrestore', 02:06:25 INFO - 'sessionrestore_no_auto_restore', 02:06:25 INFO - 'tabpaint']}, 02:06:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:06:25 INFO - 'tests': ['tsvgx', 02:06:25 INFO - 'tsvgr_opacity', 02:06:25 INFO - 'tart', 02:06:25 INFO - 'tscrollx', 02:06:25 INFO - 'cart']}, 02:06:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:06:25 INFO - 'tsvgr_opacity', 02:06:25 INFO - 'tart', 02:06:25 INFO - 'tscrollx', 02:06:25 INFO - 'cart']}, 02:06:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:25 INFO - 'talos_options': ['--disable-e10s'], 02:06:25 INFO - 'tests': ['tp5o']}, 02:06:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:25 INFO - 'tests': ['tp5o']}, 02:06:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:25 INFO - 'talos_options': ['--disable-e10s', 02:06:25 INFO - '--xperf_path', 02:06:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:06:25 INFO - 'tests': ['tp5n']}, 02:06:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:25 INFO - 'talos_options': ['--xperf_path', 02:06:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:06:25 INFO - 'tests': ['tp5n']}}, 02:06:25 INFO - 'talos.zip': {'path': '', 02:06:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:06:25 INFO - Running post-action listener: _resource_record_post_action 02:06:25 INFO - [mozharness: 2016-12-24 10:06:25.516593Z] Finished populate-webroot step (success) 02:06:25 INFO - [mozharness: 2016-12-24 10:06:25.516700Z] Running create-virtualenv step. 02:06:25 INFO - Running pre-action listener: _resource_record_pre_action 02:06:25 INFO - Running main action method: create_virtualenv 02:06:25 INFO - Creating virtualenv /builds/slave/test/build/venv 02:06:25 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:06:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:06:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:06:25 INFO - Using real prefix '/tools/python27' 02:06:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:06:26 INFO - Installing distribute.............................................................................................................................................................................................done. 02:06:29 INFO - Installing pip.................done. 02:06:29 INFO - Return code: 0 02:06:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:06:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:06:29 INFO - Reading from file tmpfile_stdout 02:06:29 INFO - Output received: 02:06:29 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:06:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:29 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 0x10310b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103081570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f1fd30>, '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 0x102d85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f17b20>, '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.o9epAFFiIK/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.gXfmfiRnh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:06:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:06:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:06:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o9epAFFiIK/Render', 02:06:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:29 INFO - 'HOME': '/Users/cltbld', 02:06:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:29 INFO - 'LOGNAME': 'cltbld', 02:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:29 INFO - 'MOZ_NO_REMOTE': '1', 02:06:29 INFO - 'NO_EM_RESTART': '1', 02:06:29 INFO - 'PAGER': '/bin/cat', 02:06:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:29 INFO - 'PWD': '/builds/slave/test', 02:06:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:29 INFO - 'SHELL': '/bin/bash', 02:06:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXfmfiRnh3/Listeners', 02:06:29 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 02:06:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:29 INFO - 'USER': 'cltbld', 02:06:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:29 INFO - 'XPC_FLAGS': '0x0', 02:06:29 INFO - 'XPC_SERVICE_NAME': '0', 02:06:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:06:29 INFO - Cleaning up... 02:06:29 INFO - Return code: 0 02:06:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:29 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 0x10310b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103081570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f1fd30>, '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 0x102d85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f17b20>, '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.o9epAFFiIK/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.gXfmfiRnh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:06:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:06:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:06:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o9epAFFiIK/Render', 02:06:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:29 INFO - 'HOME': '/Users/cltbld', 02:06:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:29 INFO - 'LOGNAME': 'cltbld', 02:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:29 INFO - 'MOZ_NO_REMOTE': '1', 02:06:29 INFO - 'NO_EM_RESTART': '1', 02:06:29 INFO - 'PAGER': '/bin/cat', 02:06:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:29 INFO - 'PWD': '/builds/slave/test', 02:06:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:29 INFO - 'SHELL': '/bin/bash', 02:06:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXfmfiRnh3/Listeners', 02:06:29 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 02:06:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:29 INFO - 'USER': 'cltbld', 02:06:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:29 INFO - 'XPC_FLAGS': '0x0', 02:06:29 INFO - 'XPC_SERVICE_NAME': '0', 02:06:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:29 INFO - Downloading/unpacking psutil>=3.1.1 02:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:06:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:06:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:06:34 INFO - Installing collected packages: psutil 02:06:34 INFO - Running setup.py install for psutil 02:06:34 INFO - building 'psutil._psutil_osx' extension 02:06:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:06:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:06:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:06:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:06:34 INFO - building 'psutil._psutil_posix' extension 02:06:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:06:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:06:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:06:35 INFO - ^ 02:06:35 INFO - 1 warning generated. 02:06:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:06:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:06:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:06:35 INFO - Successfully installed psutil 02:06:35 INFO - Cleaning up... 02:06:35 INFO - Return code: 0 02:06:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:35 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 0x10310b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103081570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f1fd30>, '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 0x102d85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f17b20>, '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.o9epAFFiIK/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.gXfmfiRnh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:06:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:06:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o9epAFFiIK/Render', 02:06:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:35 INFO - 'HOME': '/Users/cltbld', 02:06:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:35 INFO - 'LOGNAME': 'cltbld', 02:06:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:35 INFO - 'MOZ_NO_REMOTE': '1', 02:06:35 INFO - 'NO_EM_RESTART': '1', 02:06:35 INFO - 'PAGER': '/bin/cat', 02:06:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:35 INFO - 'PWD': '/builds/slave/test', 02:06:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:35 INFO - 'SHELL': '/bin/bash', 02:06:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXfmfiRnh3/Listeners', 02:06:35 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 02:06:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:35 INFO - 'USER': 'cltbld', 02:06:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:35 INFO - 'XPC_FLAGS': '0x0', 02:06:35 INFO - 'XPC_SERVICE_NAME': '0', 02:06:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:06:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:06:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:06:38 INFO - Installing collected packages: mozsystemmonitor 02:06:38 INFO - Running setup.py install for mozsystemmonitor 02:06:38 INFO - Successfully installed mozsystemmonitor 02:06:38 INFO - Cleaning up... 02:06:38 INFO - Return code: 0 02:06:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:06: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')]} 02:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06: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')]} 02:06:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06: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', '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 0x10310b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103081570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f1fd30>, '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 0x102d85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f17b20>, '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.o9epAFFiIK/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.gXfmfiRnh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:06: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:06: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 jsonschema==2.5.1 02:06:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o9epAFFiIK/Render', 02:06:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:38 INFO - 'HOME': '/Users/cltbld', 02:06:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:38 INFO - 'LOGNAME': 'cltbld', 02:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:38 INFO - 'MOZ_NO_REMOTE': '1', 02:06:38 INFO - 'NO_EM_RESTART': '1', 02:06:38 INFO - 'PAGER': '/bin/cat', 02:06:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:38 INFO - 'PWD': '/builds/slave/test', 02:06:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:38 INFO - 'SHELL': '/bin/bash', 02:06:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXfmfiRnh3/Listeners', 02:06:38 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 02:06:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:38 INFO - 'USER': 'cltbld', 02:06:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:38 INFO - 'XPC_FLAGS': '0x0', 02:06:38 INFO - 'XPC_SERVICE_NAME': '0', 02:06:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:38 INFO - Downloading/unpacking jsonschema==2.5.1 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:06:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:42 INFO - Downloading functools32-3.2.3-2.tar.gz 02:06:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:06:42 INFO - warning: no files found matching '*.txt' 02:06:42 INFO - no previously-included directories found matching 'build' 02:06:42 INFO - no previously-included directories found matching 'dist' 02:06:42 INFO - no previously-included directories found matching '.git*' 02:06:42 INFO - Installing collected packages: jsonschema, functools32 02:06:42 INFO - Running setup.py install for functools32 02:06:42 INFO - warning: no files found matching '*.txt' 02:06:42 INFO - no previously-included directories found matching 'build' 02:06:42 INFO - no previously-included directories found matching 'dist' 02:06:42 INFO - no previously-included directories found matching '.git*' 02:06:42 INFO - Successfully installed jsonschema functools32 02:06:42 INFO - Cleaning up... 02:06:42 INFO - Return code: 0 02:06:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:42 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 0x10310b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103081570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f1fd30>, '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 0x102d85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f17b20>, '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.o9epAFFiIK/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.gXfmfiRnh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:06:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:06:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:06:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o9epAFFiIK/Render', 02:06:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:42 INFO - 'HOME': '/Users/cltbld', 02:06:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:42 INFO - 'LOGNAME': 'cltbld', 02:06:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:42 INFO - 'MOZ_NO_REMOTE': '1', 02:06:42 INFO - 'NO_EM_RESTART': '1', 02:06:42 INFO - 'PAGER': '/bin/cat', 02:06:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:42 INFO - 'PWD': '/builds/slave/test', 02:06:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:42 INFO - 'SHELL': '/bin/bash', 02:06:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXfmfiRnh3/Listeners', 02:06:42 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 02:06:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:42 INFO - 'USER': 'cltbld', 02:06:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:42 INFO - 'XPC_FLAGS': '0x0', 02:06:42 INFO - 'XPC_SERVICE_NAME': '0', 02:06:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:06:42 INFO - Cleaning up... 02:06:42 INFO - Return code: 0 02:06:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:42 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 0x10310b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103081570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f1fd30>, '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 0x102d85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f17b20>, '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.o9epAFFiIK/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.gXfmfiRnh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:06:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:06:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:06:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o9epAFFiIK/Render', 02:06:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:42 INFO - 'HOME': '/Users/cltbld', 02:06:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:42 INFO - 'LOGNAME': 'cltbld', 02:06:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:42 INFO - 'MOZ_NO_REMOTE': '1', 02:06:42 INFO - 'NO_EM_RESTART': '1', 02:06:42 INFO - 'PAGER': '/bin/cat', 02:06:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:42 INFO - 'PWD': '/builds/slave/test', 02:06:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:42 INFO - 'SHELL': '/bin/bash', 02:06:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXfmfiRnh3/Listeners', 02:06:42 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 02:06:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:42 INFO - 'USER': 'cltbld', 02:06:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:42 INFO - 'XPC_FLAGS': '0x0', 02:06:42 INFO - 'XPC_SERVICE_NAME': '0', 02:06:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:42 INFO - Downloading/unpacking blobuploader==1.2.4 02:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:45 INFO - Downloading blobuploader-1.2.4.tar.gz 02:06:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:06:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:06:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06:46 INFO - Downloading docopt-0.6.1.tar.gz 02:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:06:46 INFO - Installing collected packages: blobuploader, requests, docopt 02:06:46 INFO - Running setup.py install for blobuploader 02:06:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:06:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:06:47 INFO - Running setup.py install for requests 02:06:47 INFO - Running setup.py install for docopt 02:06:47 INFO - Successfully installed blobuploader requests docopt 02:06:47 INFO - Cleaning up... 02:06:47 INFO - Return code: 0 02:06:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:47 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 0x10310b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103081570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f1fd30>, '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 0x102d85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f17b20>, '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.o9epAFFiIK/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.gXfmfiRnh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:06:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:06:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:06:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o9epAFFiIK/Render', 02:06:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:47 INFO - 'HOME': '/Users/cltbld', 02:06:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:47 INFO - 'LOGNAME': 'cltbld', 02:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:47 INFO - 'MOZ_NO_REMOTE': '1', 02:06:47 INFO - 'NO_EM_RESTART': '1', 02:06:47 INFO - 'PAGER': '/bin/cat', 02:06:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:47 INFO - 'PWD': '/builds/slave/test', 02:06:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:47 INFO - 'SHELL': '/bin/bash', 02:06:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXfmfiRnh3/Listeners', 02:06:47 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 02:06:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:47 INFO - 'USER': 'cltbld', 02:06:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:47 INFO - 'XPC_FLAGS': '0x0', 02:06:47 INFO - 'XPC_SERVICE_NAME': '0', 02:06:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:06:48 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-_WBVNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:06:48 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-uuoVDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:06:48 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Fvmgob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:06:48 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-YfdsC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:06:48 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Ax5f_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:06:48 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-PoG9oc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:06:48 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-jmaZa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:06:48 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-5Y0AzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:06:48 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-NxaShW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:06:49 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-RC_wWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:06:49 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-jonTAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:06:49 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-szXn2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:06:49 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Dfteg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:06:49 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-c8y39G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:06:49 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-RnvNWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:06:49 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-lHZ6RW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:06:49 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-iWTbxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:06:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:06:49 INFO - Running setup.py install for manifestparser 02:06:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:06:50 INFO - Running setup.py install for mozcrash 02:06:50 INFO - Running setup.py install for mozdebug 02:06:50 INFO - Running setup.py install for mozdevice 02:06:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:06:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:06:50 INFO - Running setup.py install for mozfile 02:06:50 INFO - Running setup.py install for mozhttpd 02:06:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:06:50 INFO - Running setup.py install for mozinfo 02:06:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:06:50 INFO - Running setup.py install for mozInstall 02:06:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:06:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:06:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:06:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:06:51 INFO - Running setup.py install for mozleak 02:06:51 INFO - Running setup.py install for mozlog 02:06:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:06:51 INFO - Running setup.py install for moznetwork 02:06:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:06:51 INFO - Running setup.py install for mozprocess 02:06:51 INFO - Running setup.py install for mozprofile 02:06:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:06:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:06:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:06:51 INFO - Running setup.py install for mozrunner 02:06:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:06:51 INFO - Running setup.py install for mozscreenshot 02:06:52 INFO - Running setup.py install for moztest 02:06:52 INFO - Running setup.py install for mozversion 02:06:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:06:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:06:52 INFO - Cleaning up... 02:06:52 INFO - Return code: 0 02:06:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:06:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:52 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 0x10310b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103081570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f1fd30>, '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 0x102d85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f17b20>, '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.o9epAFFiIK/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.gXfmfiRnh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:06:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:06:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:06:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o9epAFFiIK/Render', 02:06:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:52 INFO - 'HOME': '/Users/cltbld', 02:06:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:52 INFO - 'LOGNAME': 'cltbld', 02:06:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:52 INFO - 'MOZ_NO_REMOTE': '1', 02:06:52 INFO - 'NO_EM_RESTART': '1', 02:06:52 INFO - 'PAGER': '/bin/cat', 02:06:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:52 INFO - 'PWD': '/builds/slave/test', 02:06:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:52 INFO - 'SHELL': '/bin/bash', 02:06:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXfmfiRnh3/Listeners', 02:06:52 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 02:06:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:52 INFO - 'USER': 'cltbld', 02:06:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:52 INFO - 'XPC_FLAGS': '0x0', 02:06:52 INFO - 'XPC_SERVICE_NAME': '0', 02:06:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:06:52 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-pTiFjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:06:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:06:52 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-leMO1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:06:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:06:52 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-8tfa7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:06:53 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-0IbTMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:06:53 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-r9QDjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:06:53 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-XIR8qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:06:53 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-pqomSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:06:53 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-OdoZA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:06:53 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-As1B1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:06:53 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-i_qVUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:06:53 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-UQjlWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:06:53 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-kAFhPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:06:53 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)) 02:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:06:53 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-PwWQXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:06:54 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-cXCMtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:06:54 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-xGNq6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:06:54 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-dn_boK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:06:54 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)) 02:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:06:54 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-X9ECCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:06:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06:57 INFO - Downloading blessings-1.6.tar.gz 02:06:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:06:57 INFO - Installing collected packages: blessings 02:06:57 INFO - Running setup.py install for blessings 02:06:57 INFO - Successfully installed blessings 02:06:57 INFO - Cleaning up... 02:06:57 INFO - Return code: 0 02:06:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:06:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:06:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:06:57 INFO - Reading from file tmpfile_stdout 02:06:57 INFO - Current package versions: 02:06:57 INFO - blessings == 1.6 02:06:57 INFO - blobuploader == 1.2.4 02:06:57 INFO - docopt == 0.6.1 02:06:57 INFO - functools32 == 3.2.3-2 02:06:57 INFO - jsonschema == 2.5.1 02:06:57 INFO - manifestparser == 1.1 02:06:57 INFO - mozInstall == 1.12 02:06:57 INFO - mozcrash == 1.0 02:06:57 INFO - mozdebug == 0.1 02:06:57 INFO - mozdevice == 0.48 02:06:57 INFO - mozfile == 1.2 02:06:57 INFO - mozhttpd == 0.7 02:06:57 INFO - mozinfo == 0.9 02:06:57 INFO - mozleak == 0.1 02:06:57 INFO - mozlog == 3.4 02:06:57 INFO - moznetwork == 0.27 02:06:57 INFO - mozprocess == 0.23 02:06:57 INFO - mozprofile == 0.28 02:06:57 INFO - mozrunner == 6.13 02:06:57 INFO - mozscreenshot == 0.1 02:06:57 INFO - mozsystemmonitor == 0.3 02:06:57 INFO - moztest == 0.7 02:06:57 INFO - mozversion == 1.4 02:06:57 INFO - psutil == 3.1.1 02:06:57 INFO - requests == 1.2.3 02:06:57 INFO - wsgiref == 0.1.2 02:06:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:06:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:57 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 0x10310b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103081570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f1fd30>, '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 0x102d85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f17b20>, '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.o9epAFFiIK/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.gXfmfiRnh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:06:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:06:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:06:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o9epAFFiIK/Render', 02:06:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:57 INFO - 'HOME': '/Users/cltbld', 02:06:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:57 INFO - 'LOGNAME': 'cltbld', 02:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:57 INFO - 'MOZ_NO_REMOTE': '1', 02:06:57 INFO - 'NO_EM_RESTART': '1', 02:06:57 INFO - 'PAGER': '/bin/cat', 02:06:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:57 INFO - 'PWD': '/builds/slave/test', 02:06:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:57 INFO - 'SHELL': '/bin/bash', 02:06:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXfmfiRnh3/Listeners', 02:06:57 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 02:06:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:57 INFO - 'USER': 'cltbld', 02:06:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:57 INFO - 'XPC_FLAGS': '0x0', 02:06:57 INFO - 'XPC_SERVICE_NAME': '0', 02:06:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:06:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:06:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:06:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:06:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:06:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:06:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:06:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:06:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:06:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:06:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:06:58 INFO - Cleaning up... 02:06:58 INFO - Return code: 0 02:06:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:06:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10310b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103081570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e84b2370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f1fd30>, '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 0x102d85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f17b20>, '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.o9epAFFiIK/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.gXfmfiRnh3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:06:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:06:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:06:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o9epAFFiIK/Render', 02:06:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:58 INFO - 'HOME': '/Users/cltbld', 02:06:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:58 INFO - 'LOGNAME': 'cltbld', 02:06:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:58 INFO - 'MOZ_NO_REMOTE': '1', 02:06:58 INFO - 'NO_EM_RESTART': '1', 02:06:58 INFO - 'PAGER': '/bin/cat', 02:06:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:58 INFO - 'PWD': '/builds/slave/test', 02:06:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:58 INFO - 'SHELL': '/bin/bash', 02:06:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXfmfiRnh3/Listeners', 02:06:58 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 02:06:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:58 INFO - 'USER': 'cltbld', 02:06:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:58 INFO - 'XPC_FLAGS': '0x0', 02:06:58 INFO - 'XPC_SERVICE_NAME': '0', 02:06:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:06:58 INFO - Cleaning up... 02:06:58 INFO - Return code: 0 02:06:58 INFO - Running post-action listener: _resource_record_post_action 02:06:58 INFO - Running post-action listener: _start_resource_monitoring 02:06:58 INFO - Starting resource monitoring. 02:06:58 INFO - [mozharness: 2016-12-24 10:06:58.361396Z] Finished create-virtualenv step (success) 02:06:58 INFO - [mozharness: 2016-12-24 10:06:58.362394Z] Running install step. 02:06:58 INFO - Running pre-action listener: _resource_record_pre_action 02:06:58 INFO - Running main action method: install 02:06:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:06:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:06:58 INFO - Reading from file tmpfile_stdout 02:06:58 INFO - Detecting whether we're running mozinstall >=1.0... 02:06:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:06:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:06:58 INFO - Reading from file tmpfile_stdout 02:06:58 INFO - Output received: 02:06:58 INFO - Usage: mozinstall [options] installer 02:06:58 INFO - Options: 02:06:58 INFO - -h, --help show this help message and exit 02:06:58 INFO - -d DEST, --destination=DEST 02:06:58 INFO - Directory to install application into. [default: 02:06:58 INFO - "/builds/slave/test"] 02:06:58 INFO - --app=APP Application being installed. [default: firefox] 02:06:58 INFO - mkdir: /builds/slave/test/build/application 02:06:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:06:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:07:30 INFO - Reading from file tmpfile_stdout 02:07:30 INFO - Output received: 02:07:30 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:07:30 INFO - Running post-action listener: _resource_record_post_action 02:07:30 INFO - [mozharness: 2016-12-24 10:07:30.014884Z] Finished install step (success) 02:07:30 INFO - [mozharness: 2016-12-24 10:07:30.015019Z] Running run-tests step. 02:07:30 INFO - Running pre-action listener: _resource_record_pre_action 02:07:30 INFO - Running main action method: run_tests 02:07:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:07:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:07:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:07:30 INFO - 2016-12-24 02:07:30.029 screenresolution[1742:18765] starting screenresolution argv=screenresolution get 02:07:30 INFO - 2016-12-24 02:07:30.032 screenresolution[1742:18765] Display 0: 1600x1200x32@60 02:07:30 INFO - 2016-12-24 02:07:30.037 screenresolution[1743:18768] starting screenresolution argv=screenresolution list 02:07:30 INFO - Available Modes on Display 0 02:07:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:07:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:07:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:07:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:07:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:07:30 INFO - Intel Iris: 02:07:30 INFO - Chipset Model: Intel Iris 02:07:30 INFO - Type: GPU 02:07:30 INFO - Bus: Built-In 02:07:30 INFO - VRAM (Dynamic, Max): 1536 MB 02:07:30 INFO - Vendor: Intel (0x8086) 02:07:30 INFO - Device ID: 0x0a2e 02:07:30 INFO - Revision ID: 0x0009 02:07:30 INFO - Displays: 02:07:30 INFO - Display: 02:07:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:07:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:07:30 INFO - Display Serial Number: 1600x1200 60 02:07:30 INFO - Main Display: Yes 02:07:30 INFO - Mirror: Off 02:07:30 INFO - Online: Yes 02:07:30 INFO - Rotation: Supported 02:07:30 INFO - Return code: 0 02:07:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:07:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:07:30 INFO - Python 2.7.3 02:07:30 INFO - Return code: 0 02:07:30 INFO - grabbing minidump binary from tooltool 02:07: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')]} 02:07:30 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 0x102f1fd30>, '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 0x102d85db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f17b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:07:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:07:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:07:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:07:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:07:30 INFO - Return code: 0 02:07:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:07:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:07:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:07:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:07:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:07:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:07:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0002 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:07:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o9epAFFiIK/Render', 02:07:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:07:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:07:30 INFO - 'HOME': '/Users/cltbld', 02:07:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:07:30 INFO - 'LOGNAME': 'cltbld', 02:07:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:07:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:07:30 INFO - 'MOZ_NO_REMOTE': '1', 02:07:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:07:30 INFO - 'NO_EM_RESTART': '1', 02:07:30 INFO - 'PAGER': '/bin/cat', 02:07:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:07:30 INFO - 'PWD': '/builds/slave/test', 02:07:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:07:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:07:30 INFO - 'SHELL': '/bin/bash', 02:07:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gXfmfiRnh3/Listeners', 02:07:30 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 02:07:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:07:30 INFO - 'USER': 'cltbld', 02:07:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:07:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:07:30 INFO - 'XPC_FLAGS': '0x0', 02:07:30 INFO - 'XPC_SERVICE_NAME': '0', 02:07:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:07:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:07:30 INFO - mozversion application_buildid: 20161224004004 02:07:30 INFO - mozversion application_changeset: ec2672807887aa46bb4c2966478afa08d610d72d 02:07:30 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:07:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:07:30 INFO - mozversion application_name: Firefox 02:07:30 INFO - mozversion application_remotingname: firefox-dev 02:07:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:07:30 INFO - mozversion application_vendor: Mozilla 02:07:30 INFO - mozversion application_version: 52.0a2 02:07:30 INFO - mozversion platform_buildid: 20161224004004 02:07:30 INFO - mozversion platform_changeset: ec2672807887aa46bb4c2966478afa08d610d72d 02:07:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:07:30 INFO - mozversion platform_version: 52.0a2 02:07:30 INFO - using testdate: 1482574050 02:07:30 INFO - actual date: 1482574050 02:07:30 INFO - starting webserver on 'localhost:49244' 02:07:30 INFO - SUITE-START | Running 2 tests 02:07:30 INFO - TEST-START | dromaeo_css 02:07:30 INFO - operating with platform_type : mac_ 02:07:30 INFO - Initialising browser for dromaeo_css test... 02:07:30 INFO - TEST-INFO | started process 1753 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmphusFGN/profile http://localhost:49244/getInfo.html) 02:07:34 INFO - PROCESS | 1753 | 2016-12-24 02:07:34.074 plugin-container[1756:18954] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 02:07:34 INFO - PROCESS | 1753 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:07:34 INFO - PROCESS | 1753 | 2016-12-24 02:07:34.074 plugin-container[1756:18954] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 02:07:34 INFO - PROCESS | 1753 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:07:34 INFO - PROCESS | 1753 | 2016-12-24 02:07:34.074 plugin-container[1756:18954] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:07:34 INFO - PROCESS | 1753 | __metrics Screen width/height:1600/1200 02:07:34 INFO - PROCESS | 1753 | colorDepth:24 02:07:34 INFO - PROCESS | 1753 | Browser inner width/height: 1024/689 02:07:34 INFO - PROCESS | 1753 | __metrics 02:07:34 ERROR - PROCESS | 1753 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:07:34 INFO - TEST-INFO | 1753: exit 0 02:07:34 INFO - Browser initialized. 02:07:34 INFO - Running cycle 1/1 for dromaeo_css test... 02:07:34 INFO - TEST-INFO | started process 1758 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmphusFGN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:07:37 INFO - PROCESS | 1758 | 2016-12-24 02:07:37.623 plugin-container[1759:19234] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833b, name = 'com.apple.tsm.portname' 02:07:37 INFO - PROCESS | 1758 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:07:37 INFO - PROCESS | 1758 | 2016-12-24 02:07:37.624 plugin-container[1759:19234] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 02:07:37 INFO - PROCESS | 1758 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:07:37 INFO - PROCESS | 1758 | 2016-12-24 02:07:37.624 plugin-container[1759:19234] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:07:47 INFO - PROCESS | 1758 | RSS: Main: 229486592 02:07:47 INFO - PROCESS | 1758 | 02:08:36 INFO - PROCESS | 1758 | 1482574116962 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] 02:10:50 INFO - PROCESS | 1758 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 02:10:53 INFO - PROCESS | 1758 | RSS: Main: 232280064 02:10:53 INFO - PROCESS | 1758 | 02:13:55 INFO - PROCESS | 1758 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 02:13:56 INFO - PROCESS | 1758 | RSS: Main: 246476800 02:13:56 INFO - PROCESS | 1758 | 02:16:58 INFO - PROCESS | 1758 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 02:16:58 INFO - PROCESS | 1758 | RSS: Main: 249344000 02:16:58 INFO - PROCESS | 1758 | 02:20:00 INFO - PROCESS | 1758 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 02:20:01 INFO - PROCESS | 1758 | RSS: Main: 249528320 02:20:01 INFO - PROCESS | 1758 | 02:23:04 INFO - PROCESS | 1758 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 02:23:04 INFO - PROCESS | 1758 | RSS: Main: 252960768 02:23:04 INFO - PROCESS | 1758 | 02:26:07 INFO - PROCESS | 1758 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 02:26:07 INFO - PROCESS | 1758 | RSS: Main: 254148608 02:26:07 INFO - PROCESS | 1758 | 02:26:07 INFO - PROCESS | 1758 | __start_tp_report 02:26:07 INFO - PROCESS | 1758 | _x_x_mozilla_page_load 02:26:07 INFO - PROCESS | 1758 | _x_x_mozilla_page_load_details 02:26:07 INFO - PROCESS | 1758 | |i|pagename|runs| 02:26:07 INFO - PROCESS | 1758 | |0;dojo.html;3344.6655334466554;3355.547001154843;3356.4461863792476;3384.272381437992;3397.6942075213224;5073.467285934979;5373.355197376311;5662.5186859116975;5701.173329867167;5758.913616295759;5721.713914304279;5745.195672605828;5805.216295800065;5822.592418530705;5851.970740146304;5037.319961805164;5256.842294731164;5288.233206185101;5294.894102117955;5318.813841515548;5319.734013299339;5345.465453454663;5345.625806193568;5369.543588794957;5377.274068000809;5128.769205385766;5167.173252279636;5323.441038690939;5323.467653234665;5334.386545547273;5168.664036837631;5222.712750798704;5224.947750522484;5242.423333433319;5251.658642188246;5108.463611320795;5185.714785686786;5213.296205012312;5228.92156617651;5292.311999440087;5809.970950145264;5815.767369305223;5833.679147646878;5839.357670656224;5862.413758624146;6031.733335999402;6160.938390616081;6203.534734894856;6255.655938923357;6266.811995640138;6208.906866396963;6238.750449981995;6272.78045268418;6279.905801412982;6284.402981716729;5794.797182098607;5809.302883653989;5819.359870414251;5820.912686309709;5849.64902105875;6166.383361663798;6211.627302361872;6223.253209614875;6253.96873015632;6274.498040156776;6083.66539840313;6110.072772100112;6130.111133885562;6144.801763656044;6173.536984726164;5288.788448462018;6081.783643271364;6222.564420490613;6244.562880598314;6273.811785646438;4994.950050499521;5817.418258174149;6186.350433204449;6200.968995154995;6245.843853903598;4474.6867719259335;6162.845928851725;6188.9690551546955;6203.8138855834395;6209.037599172136;4141.565135660712;4917.803287868445;4971.801127954841;4974.204127339608;4986.476419975923;4406.3170208617075;4424.247877860771;4428.446444194476;4457.687961842705;4462.241418958784;5523.28197334352;5724.370319264877;5760.51035661965;5776;5945.1973983512535;5184.8185313514605;6198.0702894566175;6220.7511699531515;6262.060690896311;6288.930881750113;5727.3699893011735;5796.246487956623;5813.215215945877;5856.238688970407;5886.352501224862;5405.243265942771;5419.620626556182;5422.267993820863;5445.918311225335;5447.673139611636;5291.3121294231505;6217.502599792097;6227;6264.467520260738;6355.332690067661;6251.156093927443;6277.529185311028;6289.339619339903;6295.905561416491;6307.085472606538;6195.96902015487;6262.561620686508;6262.997920332907;6271.278802937721;6322.652254126067;6216;6226.906596400967;6245.781397651061;6255.124282600531;6256.124142619946;6209.379062093754;6224.128621993015;6249.468795152281;6260.718267677874;6287.748490060347;6003.45968862787;6029.125776762433;6074.997625391938;6166.630002199882;6207.317195108444;6112.442689633966;6134.202553667995;6136.5090792737365;6140.478059365004;6142.846428839315;5870.589058765845;5965.970170149121;5986.551008674454;6002.549808764447;6003.969980150071;5902.822915312548;6025.638461692312;6121.392004143408;6134.202553667995;6176.876462470816;6025.8192254232445;6038.818835434944;6050.062240352752;6060.393960603904;6075.513958883191 02:26:07 INFO - PROCESS | 1758 | |1;ext.html;472.01349180213344;485.75712143928024;501.6237821633775;506.3164727617716;510.56091761085435;26067.47865042698;39120.608793912055;39122.413163802565;39145.60854391459;39152.60847391525;39802;40375;40416.19167616646;40447.59552404475;40449.59550404495;8380.371472139583;8856.937167539872;9112.635494580225;9317.114874086985;9327.580258888333;8379.916200837975;9045.728628141167;9350.859737103949;9404.153626173644;9416.905830941705;9365.859512107323;9367.15695587397;9412.341136120474;9420.576074076684;9444.433333999981;9223.123803238681;9297.163255307021;9318.580663870142;9368.20370268528;9372.156505914432;8340.541270230135;8880.8667869982;9177.082291770836;9232.261419086457;9307.883054033491;37889.810550947346;38296;38319;38396.80801596002;38527.42208866869;3232.4181647303476;3374.8143852088124;3381.9177863083823;3450.7584469087174;3896.7272290939645;28450.288742781388;28867.989620363187;29177.854110729524;29299.267518312;30459.69540304591;3140.1835522764018;3192;3210.96789032109;3233.3048394595276;3233.3533293341425;451028.74485627364;456159;458915;459625;461034;61285.38714612886;61677;61723;61767.6911615439;61917;58745.70627146837;58969.705151473965;59224.815503690545;59390.703046484494;59582;15937.20313984296;16087.356505739874;16302.755458668129;16306;16379.754303685455;12584.622461326175;12610.936945315398;12661.873381266254;12662.36688165588;12675.873241267655;15516.37934482608;18477.90761046186;18480.353187638368;18879.867207967567;18949.147288372056;3194.9840250798597;7657.349125324298;7973.401994850294;7997.840043199103;8015.278624923785;709.9680514376865;770.6647608290411;887.8845750052452;951.9195712865809;1059.86751656043;26272.868635656698;28936.565951510744;29516;29959.40081198364;30839.229019274248;1007.1539906478594;1054.6888667843004;1061.7398737309409;1075.9246852720391;1079.96220132295;15072;15523.301451434487;15620.609484762745;15679.216039197994;15699.607509812346;616474.917625409;619400;625870;626727;627942;57362.7131864338;57748.71125644345;57890.84218315694;57968.420315796306;58151.41848581545;1328.754180476615;1346.1250187378282;1373.8557451467652;1376.050525137652;1376.4150236149596;29393;29885.551716723832;29955;30134.69865301319;30460.93386731454;50923.490765091876;51165.72085697887;51284.487155129464;51697;51720.448386548465;625593;626526;628791;630387;631048;26796.59805102886;27094.72905271001;27126.321841954108;27142.72857271402;27189.456210876066;45604.543954561355;45795.771021146014;46307.07385852331;46441.53558464372;47256;37328.25343493169;38145.2370952585;39890;41097;42024;38342.041448964;38837;39337.213255735296;39774.60225397709;40004.599954000085;37858.053548661504;38418.615813841505;38442.80778596201;38597.80701096477;38855.61144388633;26878.32804179911;33903.98288051363;34410.52663153774;34917.30165396729;35206.47190292199;25551;25582;25679;25972.220833375028;26041.739582603932 02:26:07 INFO - PROCESS | 1758 | |2;jquery.html;356.784145591917;357.551273152194;369.86315063426537;373.3261463059177;389.6746216908881;16588.253528591213;18294.90852545737;18685.813141868595;19311.324103656374;19695;18069.00620465877;18620.16209270583;18791.624167516675;18799.24803007878;19297.807021929777;5154.819581314655;5508.366537848158;5741.397153298906;5873.383294754032;5902.734376953026;5540.113581826909;5599.300087489064;5688.573356998241;5723.828285151452;5730.828075157752;4553.225951588238;4810.591099756525;5118.7714948412295;5589.636673616223;5616.9327827712805;4613.8154473821005;4897.240927656219;5583.4974852263285;5612.540739407763;5973.820785376446;5553.194786755897;5731.484166425;5748.65508069513;5768.913466298009;5783.739731712083;11706.473208705629;11999.80001999793;13064.150830196102;13131.883789877871;13173.34133293341;2222.5665995130958;2359.575276450238;2577.9226623201334;2708.7155848635807;2928.31184671602;9769.071938165864;9817;9872.802543949081;10355.482225888676;10362.48187590625;1695.28797904879;1806.4038867173801;1824.14265295311;1846.796852346241;1911.8757280776845;87400.81497962601;106767;109016;111296.88703112946;111834;11448.141389395829;11515.481803318871;11554.826677599844;11747.823782643267;12003;14365.497207597684;14404.063735857137;14404.711905762035;14412.351444185037;14457.277136143151;8224.095349511548;8341.124181960928;8367.246947774607;8405.495670259805;8566.528840913685;8343.541105239148;8366.623501942428;8460.619272132768;8581.914180858237;8602.268807151337;4012.9799351003644;4417.823287068545;4540.909181816345;4554.316852472156;4559.9772001140445;1766.5318690547015;1914.5213696575856;1926.1524929031182;1948.6784680527837;1963.2834015584208;778.7741554949115;866.3459088388227;901.8782464367226;971.9988411935717;1002.2354509811598;17244.913775431043;18755.062246887876;20130.59738805245;20904.79095209059;21203.36389908305;882.3646974178581;895.9641614335353;900.7567956651667;939.7227817793768;984.6455276100598;8045.597720113971;10403.687889363331;10629.946850265855;10822.971817677308;10853.565857365775;386742;458411.70794146485;520923.39538302063;526963;529212;62113.68943155255;81746;106502.93497065085;114483;136503;797.8563858505524;799.5282783157965;946.0917519181526;986.4673076538588;993.6671215142864;15100;18345.541361465537;18629.627407451502;18809.341673041377;19071.427857164308;44117;54025.91948161093;54334.45665543294;55222;55627;78218;80152;80508;80989.19010810052;80996.19003810122;6702.966485167738;7960.084590271961;8220.83558328842;8313.501189928624;8342.040665323322;9811.509424528887;12625.242485450903;12958.611241662766;13048;13075.803862942254;10802.405867677353;11104.61133860311;11233.606823761129;11281.7743645125;11352.886471135183;5181.611379146654;8481.957590212009;9237.383457894975;9615.56729947168;10999.34003959765;11365.715857103638;11480.368579728196;11572;11629.47667354988;11845;6277.874442511215;7879.487833290817;8072.425876953937;8236.711715089943;8373.665053397795;9757.51212439361;10783.6225732099;11137.220394572301;11409.25839820409;11445.48495317696 02:26:07 INFO - PROCESS | 1758 | |3;mootools.html;1448.9058211216272;1484;1644.0053767470679;1726.3008481564955;1776.7246076858091;7510.737124200654;7793.376529877611;8036.598170091494;8490.40567160299;8508.574571271443;7948.483348582338;8245.175482451752;8270.710525131622;8395.748127556182;8416.621252043657;2118.523332250243;2340.9531809363802;2342.051469154995;2342.3792694935855;2354.3407845803204;2312.8612283262974;2359.893804778789;2364.7398786133513;2367.83425160238;2370.7747763962484;2285.325828880484;2287.1880482428746;2306.6770652108717;2309.4919117794057;2312.1560630369877;2135.4341099608614;2156.3315372234656;2184.5958497677902;2188.0153930731076;2190.2224710227956;2229.1529218896876;2293.724753029647;2324.1052194904914;2327.0924339507606;2338.4387746940783;7418.406527477789;8293.129221431784;8402.78993025173;8403;8405.033421156522;5035.2195409711185;5164.431912489623;5187.299714538527;5193.506616871391;5207.3230480037355;3233.1270556949726;3319.6348401675796;3544.3088597723454;3546.716262698978;3549.7870127792416;1048.1562342314435;1057.8413238014282;1086.6577028236081;1097.9725506862392;1106.8505751723494;10860.402677852715;10904.127669786398;10911.563537458493;10914.727131821768;10995.505202265836;4499.235130027903;4566.1552612766745;4725.621950243973;4763.618910487153;4780.402449693788;7110.146782386147;9006.964199117185;9349.065093490732;9349.813003739888;9364.812703745887;5178.97410512945;5191.195364718475;5196.142636465017;5196.76614552346;5214.374275087014;3990.4014397840556;4260.041490664576;4274.871753847389;4735.644826638016;4774.641901857374;3332.9333413332083;3382.9323413531592;3753.60587138352;3772.905677358088;3782.451544526083;1104.1718710966775;1267.2776517385187;1282.313962030328;1285.4536821850677;1290.6579756364572;784.3842583571983;867.1805144138855;884.4698671298561;909.940853844498;915.0849150849151;4700.6944548604915;5467.863303417367;5515.834524964257;5531.695756733418;5534.972325138348;436.7292278787172;438.72141190343723;442.11797464058765;917.8485549884327;962.2879069488528;3444.896653100411;3709.8516059357326;3722.571904231048;3729.589745128034;3816.637419445148;8106.47307924983;9911.851322230172;9968.651097211563;10049.196064314838;10100.696979090639;9957.452340121217;9974.501274936225;9975.800483990424;9987.750306242257;10018.549165287579;7508.511946723445;7725.459217854808;7788.065432148179;7807.570583617956;7904.28861402465;5441.483059109379;5470.781168753205;5517.213807027586;5561.443855614568;5681.431856814285;8170.182981701782;9348.53257337144;9391.154796068387;9391.436513809193;9396.76508087276;9413.576389062646;9455.905440945502;9490.193333566724;9496.762580935256;9584.712458626253;6580.21037475506;6813.148356455443;6933.306669333027;7052;7255.2019277880445;7784.92215077842;7940.444168908121;7955.403344749283;7983.800404989922;8013.719519817012;6940.340667636567;7074.858502829811;7120.60836653989;7121.287871212838;7168.354848063699;6929.653517324215;6937.895931561132;6969.992244516338;7044.119485064367;7060.3292687194635;6636.634985075867;6655.900161497679;6698.66506674674;6722.731090756315;6733.225679046972;4785.521447855187;4807.879803004953;4837.37114175155;4845.079434907498;4863.635227358033;3921.745086569364;4754.21555443348;4770.880727981828;4885.65800393969;4994 02:26:07 INFO - PROCESS | 1758 | |4;prototype.html;299.561142925614;299.694311801962;303.28878779262647;306.89412152807273;310.6458637153644;4283.614474697277;4835.903281934364;5360.855657736907;5385.838424847261;5462.836114916554;5018.573421259197;5202.4537423570455;5371;5457.808976685817;5458.9454105458935;3391.6269210386777;4125.575772109157;4136.711314504961;4141.64795992341;4141.937870931938;3993.5008123984503;4119.567445418233;4130.463039804821;4180;4215.768132752697;3263.037403965836;3892.9099852041472;4091.7954102294916;4105.003347556286;4162.292410290258;3258.14911952042;3602.963970360289;4113.9794301028305;4158.397032430311;4168.583141685807;3620.5698748994264;3627.927441451156;3634.1290380599294;3635.745497815154;3636.563612366533;5035.244713292998;5244.737763111867;5264.42091369949;5286.207068939651;5347.839564813062;4682.742449165295;4699.835505757282;4708.105459962596;4711.057788442326;4718.4809670936165;5632.7746890124345;5645.657052323322;5680.6591604503865;5683.687397193153;5708.743106560215;3413.607435144965;3576;3818;3857.324968130594;3958.6239307265764;4764.714117153017;4984.576311013569;5150.021495915764;6042.398764327457;6152.72349230783;3461.3769521486374;3921.3529767588598;3926.1131479834758;3931.6264954829244;3948.368261078214;3714.851405943786;3944.3294639911283;3959.742616729903;3977.622125898093;4001.8199181036925;4034.1326614777963;4622.6764126510825;4647.513580234626;4676.942145456056;4713.269443236304;5058.747062646853;5709.628874123252;5723.77104915807;5824.388439213906;5892.351841297454;1097.6706987903597;1223.07046644549;1255.2029461291963;1261.2153802369544;1269.9682507937375;716.4411758828019;767.458941446279;848.6308455821737;869.3914260018013;883.3198437203317;4591.265397536445;4816.903661926742;4884.3650325457465;5058.645894787403;5154.407243167084;4988.7777494514075;5402.702851343214;5470.699111548824;5487.862803429946;5518.179000929684;4232.793673759561;4264.893377665583;4566.977165114153;4759.309900064472;5091.937765205098;2693.6666350156797;2698.7031426543062;2812;2928.3996780659913;2935.163478408686;4832.975835120802;5560.4161563035295;5670.603057785916;5731.01986966775;5798.1302804579655;5500.67983683913;5528.419515950848;5570.888582228333;5714.2571465710025;6169.691515424211;6164.845878853064;6219.045953172297;6235.75056997715;6252.874942501216;6265.248170219603;5778.451047150514;6001;6017.789377371771;6027.397260273938;6053.334133245427;4928.679635823803;5139.8458046258675;5150.381954165524;5888.411158884077;5923.881522369615;5230.953809238244;5958.553108516792;6153.907691384722;6281.246250449975;6333.398327158912;3841.846326146923;3890.7860067696547;3949.3878448840474;3963.5441924177935;3972.463717398114;5146.716930568705;5267.552258058108;5930.15352630731;5939.67331796738;5990.460858522754;4141.544430112625;4648.86053418398;4672.8551504881525;5010.06709732858;5797.681127538026;4768.809247630056;5309.36287645485;5743.023685973311;5779.595428319977;5792.275965504312;4962.602991760578;5309.283246761638;5681.630694004876;5681.744321505459;5684.346300175367;4976;5675.155574438407;5757.7832534475365;5784.305883294031;5795.478406943395;4239.088595951762;5139.511746384088;5392.6225164239695;5661.980843448054;5756.165356023437 02:26:07 INFO - PROCESS | 1758 | |5;yui.html;651.8794023105722;683.2859661653572;713.8857782754759;717.8348979734665;771.7761849063771;13837.238951857657;14711.852881471183;14719.852801471981;14726.410943562272;14759.631009224775;13277.800832987514;13322.666933326671;13325.866741332584;13393;13407.530736424227;642.6401215319413;664.1697877652933;772.4284029817936;783.6943591999125;814.1248158229897;800.8278220182659;806.8628333183373;809.6842231529708;810.3638643664724;811.4765975945512;844.7845799321165;845.8900342955438;858.4420126917491;861.732862812527;862.2886118951858;843.7510934274343;848.8854004709347;849.1762989899818;851.3274513134617;851.5699571716306;631.0912286307703;631.6589041917405;634.4163369699887;635.2757856044113;635.5900444213456;11721.472533736003;11771.882281177164;13846.238456884867;13947.58157255284;13962;12950.48198072076;13391.866081339256;13453.865461345456;13454.865451345557;13462.865371346259;10826.891731082667;10949.562017519369;11310.491027903681;11324.603638872682;11508.942455287754;3172.254520187754;3434.4161492546077;3660.2862441823854;3774.452704357853;3781.054736315921;87658;90371.09628903758;90633.54683226607;91092;91325.99999999933;10034.64878729241;10169.847452288222;10288.228382871315;10409.739756505996;10419.218558607983;19933.60132797365;19994.70007949882;20129.194832206842;20355.491112722004;20414.08136633855;7510.924890751023;7559.1684914659345;7635.465517413728;7717.691292348355;7766.9902912621;4248.065425606362;4310.784460776948;4394.384786129945;4396.626286765661;4406.537313582027;2950.9704902950693;3336.7497437691786;3425.503302021193;3456.1186897341017;3462.1344663834043;606.9817905462843;625.7465726380823;631.2614241337646;633.762339122829;646.7445359083135;589.2310534752104;632.8516573858311;635.2155088465742;647.7538535356534;653.9934600654028;8005;8351.958240208882;8712.607932643046;8831.470111793184;9013.414128081653;534.7005676820993;619.4796371048251;621.2886245249255;649.0070192605322;666.3369946902862;4070.1859628074503;4334.848280310174;4356.869293921188;4376.934345984812;4399.0471880609775;75495;79517.60241198757;84147.5792621033;112355.43822280836;114458.28312575318;61651.691741541006;66428.67142657119;85433.57283213669;103545;104113;56849;87884.56057719671;89398.94089136564;92017;92859.5357023224;7567.356774674215;8435.198656127657;8698.91301086981;9128.908710912805;9431.952840235756;39423.802880985415;39740;39744.20511589694;44150;46560;54220.728896355264;67130;69280.65359673169;71077.64461177662;78045;12084.335361555199;12153.817692734792;12214.450349734461;12305.384730763604;12307.26156430617;13299.135556188818;13893.861061389256;13934.075129249868;13986;14293.57119286423;12392.132550721362;12570.685732856751;13028.739425211254;13030.609081727564;13258.801117983045;9662.371945823781;10276.588936442758;10643.627473038407;12571;13061.608151755463;10523.158147348347;12059.336736479578;12392.380380980734;12786.616401507597;12902.483900643869;10156.593736250466;10486.94756526243;10641.627543035956;11125;11147.163962702667;10616.416097114732;10900.454977251264;10945.507452164513;11157.776844463227;11180.993710566088 02:26:07 INFO - PROCESS | 1758 | __end_tp_report 02:26:07 INFO - PROCESS | 1758 | __start_cc_report 02:26:07 INFO - PROCESS | 1758 | _x_x_mozilla_cycle_collect,376 02:26:07 INFO - PROCESS | 1758 | __end_cc_report 02:26:07 INFO - PROCESS | 1758 | __startTimestamp1482575167539__endTimestamp 02:26:07 INFO - PROCESS | 1758 | 02:26:07 INFO - PROCESS | 1758 | ------- Summary: start ------- 02:26:07 INFO - PROCESS | 1758 | Number of tests: 6 02:26:07 INFO - PROCESS | 1758 | 02:26:07 INFO - PROCESS | 1758 | [#0] dojo.html Cycles:165 Average:5722.06 Median:5945.20 stddev:647.79 (10.9%) stddev-sans-first:622.35 02:26:07 INFO - PROCESS | 1758 | Values: 3344.7 3355.5 3356.4 3384.3 3397.7 5073.5 5373.4 5662.5 5701.2 5758.9 5721.7 5745.2 5805.2 5822.6 5852.0 5037.3 5256.8 5288.2 5294.9 5318.8 5319.7 5345.5 5345.6 5369.5 5377.3 5128.8 5167.2 5323.4 5323.5 5334.4 5168.7 5222.7 5224.9 5242.4 5251.7 5108.5 5185.7 5213.3 5228.9 5292.3 5810.0 5815.8 5833.7 5839.4 5862.4 6031.7 6160.9 6203.5 6255.7 6266.8 6208.9 6238.8 6272.8 6279.9 6284.4 5794.8 5809.3 5819.4 5820.9 5849.6 6166.4 6211.6 6223.3 6254.0 6274.5 6083.7 6110.1 6130.1 6144.8 6173.5 5288.8 6081.8 6222.6 6244.6 6273.8 4995.0 5817.4 6186.4 6201.0 6245.8 4474.7 6162.8 6189.0 6203.8 6209.0 4141.6 4917.8 4971.8 4974.2 4986.5 4406.3 4424.2 4428.4 4457.7 4462.2 5523.3 5724.4 5760.5 5776.0 5945.2 5184.8 6198.1 6220.8 6262.1 6288.9 5727.4 5796.2 5813.2 5856.2 5886.4 5405.2 5419.6 5422.3 5445.9 5447.7 5291.3 6217.5 6227.0 6264.5 6355.3 6251.2 6277.5 6289.3 6295.9 6307.1 6196.0 6262.6 6263.0 6271.3 6322.7 6216.0 6226.9 6245.8 6255.1 6256.1 6209.4 6224.1 6249.5 6260.7 6287.7 6003.5 6029.1 6075.0 6166.6 6207.3 6112.4 6134.2 6136.5 6140.5 6142.8 5870.6 5966.0 5986.6 6002.5 6004.0 5902.8 6025.6 6121.4 6134.2 6176.9 6025.8 6038.8 6050.1 6060.4 6075.5 02:26:07 INFO - PROCESS | 1758 | 02:26:07 INFO - PROCESS | 1758 | [#1] ext.html Cycles:180 Average:69939.19 Median:39244.91 stddev:153873.81 (392.1%) stddev-sans-first:154217.07 02:26:07 INFO - PROCESS | 1758 | Values: 472.0 485.8 501.6 506.3 510.6 26067.5 39120.6 39122.4 39145.6 39152.6 39802.0 40375.0 40416.2 40447.6 40449.6 8380.4 8856.9 9112.6 9317.1 9327.6 8379.9 9045.7 9350.9 9404.2 9416.9 9365.9 9367.2 9412.3 9420.6 9444.4 9223.1 9297.2 9318.6 9368.2 9372.2 8340.5 8880.9 9177.1 9232.3 9307.9 37889.8 38296.0 38319.0 38396.8 38527.4 3232.4 3374.8 3381.9 3450.8 3896.7 28450.3 28868.0 29177.9 29299.3 30459.7 3140.2 3192.0 3211.0 3233.3 3233.4 451028.7 456159.0 458915.0 459625.0 461034.0 61285.4 61677.0 61723.0 61767.7 61917.0 58745.7 58969.7 59224.8 59390.7 59582.0 15937.2 16087.4 16302.8 16306.0 16379.8 12584.6 12610.9 12661.9 12662.4 12675.9 15516.4 18477.9 18480.4 18879.9 18949.1 3195.0 7657.3 7973.4 7997.8 8015.3 710.0 770.7 887.9 951.9 1059.9 26272.9 28936.6 29516.0 29959.4 30839.2 1007.2 1054.7 1061.7 1075.9 1080.0 15072.0 15523.3 15620.6 15679.2 15699.6 616474.9 619400.0 625870.0 626727.0 627942.0 57362.7 57748.7 57890.8 57968.4 58151.4 1328.8 1346.1 1373.9 1376.1 1376.4 29393.0 29885.6 29955.0 30134.7 30460.9 50923.5 51165.7 51284.5 51697.0 51720.4 625593.0 626526.0 628791.0 630387.0 631048.0 26796.6 27094.7 27126.3 27142.7 27189.5 45604.5 45795.8 46307.1 46441.5 47256.0 37328.3 38145.2 39890.0 41097.0 42024.0 38342.0 38837.0 39337.2 39774.6 40004.6 37858.1 38418.6 38442.8 38597.8 38855.6 26878.3 33904.0 34410.5 34917.3 35206.5 25551.0 25582.0 25679.0 25972.2 26041.7 02:26:07 INFO - PROCESS | 1758 | 02:26:07 INFO - PROCESS | 1758 | [#2] jquery.html Cycles:180 Average:30139.17 Median:24267.41 stddev:81767.00 (336.9%) stddev-sans-first:81965.80 02:26:07 INFO - PROCESS | 1758 | Values: 356.8 357.6 369.9 373.3 389.7 16588.3 18294.9 18685.8 19311.3 19695.0 18069.0 18620.2 18791.6 18799.2 19297.8 5154.8 5508.4 5741.4 5873.4 5902.7 5540.1 5599.3 5688.6 5723.8 5730.8 4553.2 4810.6 5118.8 5589.6 5616.9 4613.8 4897.2 5583.5 5612.5 5973.8 5553.2 5731.5 5748.7 5768.9 5783.7 11706.5 11999.8 13064.2 13131.9 13173.3 2222.6 2359.6 2577.9 2708.7 2928.3 9769.1 9817.0 9872.8 10355.5 10362.5 1695.3 1806.4 1824.1 1846.8 1911.9 87400.8 106767.0 109016.0 111296.9 111834.0 11448.1 11515.5 11554.8 11747.8 12003.0 14365.5 14404.1 14404.7 14412.4 14457.3 8224.1 8341.1 8367.2 8405.5 8566.5 8343.5 8366.6 8460.6 8581.9 8602.3 4013.0 4417.8 4540.9 4554.3 4560.0 1766.5 1914.5 1926.2 1948.7 1963.3 778.8 866.3 901.9 972.0 1002.2 17244.9 18755.1 20130.6 20904.8 21203.4 882.4 896.0 900.8 939.7 984.6 8045.6 10403.7 10629.9 10823.0 10853.6 386742.0 458411.7 520923.4 526963.0 529212.0 62113.7 81746.0 106502.9 114483.0 136503.0 797.9 799.5 946.1 986.5 993.7 15100.0 18345.5 18629.6 18809.3 19071.4 44117.0 54025.9 54334.5 55222.0 55627.0 78218.0 80152.0 80508.0 80989.2 80996.2 6703.0 7960.1 8220.8 8313.5 8342.0 9811.5 12625.2 12958.6 13048.0 13075.8 10802.4 11104.6 11233.6 11281.8 11352.9 5181.6 8482.0 9237.4 9615.6 10999.3 11365.7 11480.4 11572.0 11629.5 11845.0 6277.9 7879.5 8072.4 8236.7 8373.7 9757.5 10783.6 11137.2 11409.3 11445.5 02:26:07 INFO - PROCESS | 1758 | 02:26:07 INFO - PROCESS | 1758 | [#3] mootools.html Cycles:180 Average:5369.07 Median:5189.25 stddev:2957.24 (57.0%) stddev-sans-first:2950.86 02:26:07 INFO - PROCESS | 1758 | Values: 1448.9 1484.0 1644.0 1726.3 1776.7 7510.7 7793.4 8036.6 8490.4 8508.6 7948.5 8245.2 8270.7 8395.7 8416.6 2118.5 2341.0 2342.1 2342.4 2354.3 2312.9 2359.9 2364.7 2367.8 2370.8 2285.3 2287.2 2306.7 2309.5 2312.2 2135.4 2156.3 2184.6 2188.0 2190.2 2229.2 2293.7 2324.1 2327.1 2338.4 7418.4 8293.1 8402.8 8403.0 8405.0 5035.2 5164.4 5187.3 5193.5 5207.3 3233.1 3319.6 3544.3 3546.7 3549.8 1048.2 1057.8 1086.7 1098.0 1106.9 10860.4 10904.1 10911.6 10914.7 10995.5 4499.2 4566.2 4725.6 4763.6 4780.4 7110.1 9007.0 9349.1 9349.8 9364.8 5179.0 5191.2 5196.1 5196.8 5214.4 3990.4 4260.0 4274.9 4735.6 4774.6 3332.9 3382.9 3753.6 3772.9 3782.5 1104.2 1267.3 1282.3 1285.5 1290.7 784.4 867.2 884.5 909.9 915.1 4700.7 5467.9 5515.8 5531.7 5535.0 436.7 438.7 442.1 917.8 962.3 3444.9 3709.9 3722.6 3729.6 3816.6 8106.5 9911.9 9968.7 10049.2 10100.7 9957.5 9974.5 9975.8 9987.8 10018.5 7508.5 7725.5 7788.1 7807.6 7904.3 5441.5 5470.8 5517.2 5561.4 5681.4 8170.2 9348.5 9391.2 9391.4 9396.8 9413.6 9455.9 9490.2 9496.8 9584.7 6580.2 6813.1 6933.3 7052.0 7255.2 7784.9 7940.4 7955.4 7983.8 8013.7 6940.3 7074.9 7120.6 7121.3 7168.4 6929.7 6937.9 6970.0 7044.1 7060.3 6636.6 6655.9 6698.7 6722.7 6733.2 4785.5 4807.9 4837.4 4845.1 4863.6 3921.7 4754.2 4770.9 4885.7 4994.0 02:26:07 INFO - PROCESS | 1758 | 02:26:07 INFO - PROCESS | 1758 | [#4] prototype.html Cycles:180 Average:4544.73 Median:4999.42 stddev:1428.39 (28.6%) stddev-sans-first:1396.40 02:26:07 INFO - PROCESS | 1758 | Values: 299.6 299.7 303.3 306.9 310.6 4283.6 4835.9 5360.9 5385.8 5462.8 5018.6 5202.5 5371.0 5457.8 5458.9 3391.6 4125.6 4136.7 4141.6 4141.9 3993.5 4119.6 4130.5 4180.0 4215.8 3263.0 3892.9 4091.8 4105.0 4162.3 3258.1 3603.0 4114.0 4158.4 4168.6 3620.6 3627.9 3634.1 3635.7 3636.6 5035.2 5244.7 5264.4 5286.2 5347.8 4682.7 4699.8 4708.1 4711.1 4718.5 5632.8 5645.7 5680.7 5683.7 5708.7 3413.6 3576.0 3818.0 3857.3 3958.6 4764.7 4984.6 5150.0 6042.4 6152.7 3461.4 3921.4 3926.1 3931.6 3948.4 3714.9 3944.3 3959.7 3977.6 4001.8 4034.1 4622.7 4647.5 4676.9 4713.3 5058.7 5709.6 5723.8 5824.4 5892.4 1097.7 1223.1 1255.2 1261.2 1270.0 716.4 767.5 848.6 869.4 883.3 4591.3 4816.9 4884.4 5058.6 5154.4 4988.8 5402.7 5470.7 5487.9 5518.2 4232.8 4264.9 4567.0 4759.3 5091.9 2693.7 2698.7 2812.0 2928.4 2935.2 4833.0 5560.4 5670.6 5731.0 5798.1 5500.7 5528.4 5570.9 5714.3 6169.7 6164.8 6219.0 6235.8 6252.9 6265.2 5778.5 6001.0 6017.8 6027.4 6053.3 4928.7 5139.8 5150.4 5888.4 5923.9 5231.0 5958.6 6153.9 6281.2 6333.4 3841.8 3890.8 3949.4 3963.5 3972.5 5146.7 5267.6 5930.2 5939.7 5990.5 4141.5 4648.9 4672.9 5010.1 5797.7 4768.8 5309.4 5743.0 5779.6 5792.3 4962.6 5309.3 5681.6 5681.7 5684.3 4976.0 5675.2 5757.8 5784.3 5795.5 4239.1 5139.5 5392.6 5662.0 5756.2 02:26:07 INFO - PROCESS | 1758 | 02:26:07 INFO - PROCESS | 1758 | [#5] yui.html Cycles:180 Average:19114.24 Median:4366.90 stddev:27810.39 (636.8%) stddev-sans-first:27853.86 02:26:07 INFO - PROCESS | 1758 | Values: 651.9 683.3 713.9 717.8 771.8 13837.2 14711.9 14719.9 14726.4 14759.6 13277.8 13322.7 13325.9 13393.0 13407.5 642.6 664.2 772.4 783.7 814.1 800.8 806.9 809.7 810.4 811.5 844.8 845.9 858.4 861.7 862.3 843.8 848.9 849.2 851.3 851.6 631.1 631.7 634.4 635.3 635.6 11721.5 11771.9 13846.2 13947.6 13962.0 12950.5 13391.9 13453.9 13454.9 13462.9 10826.9 10949.6 11310.5 11324.6 11508.9 3172.3 3434.4 3660.3 3774.5 3781.1 87658.0 90371.1 90633.5 91092.0 91326.0 10034.6 10169.8 10288.2 10409.7 10419.2 19933.6 19994.7 20129.2 20355.5 20414.1 7510.9 7559.2 7635.5 7717.7 7767.0 4248.1 4310.8 4394.4 4396.6 4406.5 2951.0 3336.7 3425.5 3456.1 3462.1 607.0 625.7 631.3 633.8 646.7 589.2 632.9 635.2 647.8 654.0 8005.0 8352.0 8712.6 8831.5 9013.4 534.7 619.5 621.3 649.0 666.3 4070.2 4334.8 4356.9 4376.9 4399.0 75495.0 79517.6 84147.6 112355.4 114458.3 61651.7 66428.7 85433.6 103545.0 104113.0 56849.0 87884.6 89398.9 92017.0 92859.5 7567.4 8435.2 8698.9 9128.9 9432.0 39423.8 39740.0 39744.2 44150.0 46560.0 54220.7 67130.0 69280.7 71077.6 78045.0 12084.3 12153.8 12214.5 12305.4 12307.3 13299.1 13893.9 13934.1 13986.0 14293.6 12392.1 12570.7 13028.7 13030.6 13258.8 9662.4 10276.6 10643.6 12571.0 13061.6 10523.2 12059.3 12392.4 12786.6 12902.5 10156.6 10486.9 10641.6 11125.0 11147.2 10616.4 10900.5 10945.5 11157.8 11181.0 02:26:07 INFO - PROCESS | 1758 | -------- Summary: end -------- 02:26:07 INFO - PROCESS | 1758 | 02:26:08 INFO - TEST-INFO | 1758: exit 0 02:26:08 INFO - TEST-OK | dromaeo_css | took 1117578ms 02:26:08 INFO - TEST-START | kraken 02:26:08 INFO - operating with platform_type : mac_ 02:26:08 INFO - Initialising browser for kraken test... 02:26:08 INFO - TEST-INFO | started process 1764 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpjt1KEq/profile http://localhost:49244/getInfo.html) 02:26:11 INFO - PROCESS | 1764 | 2016-12-24 02:26:11.308 plugin-container[1766:21372] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 02:26:11 INFO - PROCESS | 1764 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:26:11 INFO - PROCESS | 1764 | 2016-12-24 02:26:11.309 plugin-container[1766:21372] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b17, name = 'com.apple.CFPasteboardClient' 02:26:11 INFO - PROCESS | 1764 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:26:11 INFO - PROCESS | 1764 | 2016-12-24 02:26:11.309 plugin-container[1766:21372] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:26:11 INFO - PROCESS | 1764 | __metrics Screen width/height:1600/1200 02:26:11 INFO - PROCESS | 1764 | colorDepth:24 02:26:11 INFO - PROCESS | 1764 | Browser inner width/height: 1024/689 02:26:11 INFO - PROCESS | 1764 | __metrics 02:26:11 ERROR - PROCESS | 1764 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:26:12 INFO - TEST-INFO | 1764: exit 0 02:26:12 INFO - Browser initialized. 02:26:12 INFO - Running cycle 1/1 for kraken test... 02:26:12 INFO - TEST-INFO | started process 1767 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpjt1KEq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:26:14 INFO - PROCESS | 1767 | 2016-12-24 02:26:14.944 plugin-container[1768:21493] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 02:26:14 INFO - PROCESS | 1767 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:26:14 INFO - PROCESS | 1767 | 2016-12-24 02:26:14.945 plugin-container[1768:21493] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8717, name = 'com.apple.CFPasteboardClient' 02:26:14 INFO - PROCESS | 1767 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:26:14 INFO - PROCESS | 1767 | 2016-12-24 02:26:14.945 plugin-container[1768:21493] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:26:24 INFO - PROCESS | 1767 | RSS: Main: 229203968 02:26:24 INFO - PROCESS | 1767 | 02:26:27 INFO - PROCESS | 1767 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 02:26:28 INFO - PROCESS | 1767 | RSS: Main: 241328128 02:26:28 INFO - PROCESS | 1767 | 02:26:31 INFO - PROCESS | 1767 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 02:26:31 INFO - PROCESS | 1767 | RSS: Main: 249004032 02:26:31 INFO - PROCESS | 1767 | 02:26:35 INFO - PROCESS | 1767 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 02:26:35 INFO - PROCESS | 1767 | RSS: Main: 252649472 02:26:35 INFO - PROCESS | 1767 | 02:26:37 INFO - PROCESS | 1767 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 02:26:38 INFO - PROCESS | 1767 | RSS: Main: 253988864 02:26:38 INFO - PROCESS | 1767 | 02:26:40 INFO - PROCESS | 1767 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:26:40 INFO - PROCESS | 1767 | RSS: Main: 254009344 02:26:40 INFO - PROCESS | 1767 | 02:26:43 INFO - PROCESS | 1767 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 02:26:44 INFO - PROCESS | 1767 | RSS: Main: 258854912 02:26:44 INFO - PROCESS | 1767 | 02:26:47 INFO - PROCESS | 1767 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 02:26:47 INFO - PROCESS | 1767 | RSS: Main: 258547712 02:26:47 INFO - PROCESS | 1767 | 02:26:50 INFO - PROCESS | 1767 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 02:26:51 INFO - PROCESS | 1767 | RSS: Main: 258019328 02:26:51 INFO - PROCESS | 1767 | 02:26:53 INFO - PROCESS | 1767 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:26:53 INFO - PROCESS | 1767 | RSS: Main: 257069056 02:26:53 INFO - PROCESS | 1767 | 02:26:55 INFO - PROCESS | 1767 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:26:56 INFO - PROCESS | 1767 | RSS: Main: 257142784 02:26:56 INFO - PROCESS | 1767 | 02:26:58 INFO - PROCESS | 1767 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:26:59 INFO - PROCESS | 1767 | RSS: Main: 257126400 02:26:59 INFO - PROCESS | 1767 | 02:27:02 INFO - PROCESS | 1767 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:27:02 INFO - PROCESS | 1767 | RSS: Main: 257167360 02:27:02 INFO - PROCESS | 1767 | 02:27:05 INFO - PROCESS | 1767 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:27:06 INFO - PROCESS | 1767 | RSS: Main: 257454080 02:27:06 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 02:27:08 INFO - PROCESS | 1767 | RSS: Main: 259133440 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | __start_tp_report 02:27:08 INFO - PROCESS | 1767 | _x_x_mozilla_page_load 02:27:08 INFO - PROCESS | 1767 | _x_x_mozilla_page_load_details 02:27:08 INFO - PROCESS | 1767 | |i|pagename|runs| 02:27:08 INFO - PROCESS | 1767 | |0;ai-astar;115;115;119;144;173;152;118;125;122;120 02:27:08 INFO - PROCESS | 1767 | |1;audio-beat-detection;182;154;114;148;117;130;111;114;139;113 02:27:08 INFO - PROCESS | 1767 | |2;audio-dft;196;199;198;204;194;199;197;196;199;200 02:27:08 INFO - PROCESS | 1767 | |3;audio-fft;82;58;59;88;60;62;64;60;80;60 02:27:08 INFO - PROCESS | 1767 | |4;audio-oscillator;86;73;90;74;70;72;86;69;74;89 02:27:08 INFO - PROCESS | 1767 | |5;imaging-gaussian-blur;89;87;90;87;91;87;96;88;90;88 02:27:08 INFO - PROCESS | 1767 | |6;imaging-darkroom;95;95;94;151;125;95;95;96;95;108 02:27:08 INFO - PROCESS | 1767 | |7;imaging-desaturate;87;91;86;105;139;76;144;143;119;88 02:27:08 INFO - PROCESS | 1767 | |8;json-parse-financial;58;56;56;59;60;60;59;60;61;60 02:27:08 INFO - PROCESS | 1767 | |9;json-stringify-tinderbox;94;68;84;77;74;70;73;71;75;71 02:27:08 INFO - PROCESS | 1767 | |10;stanford-crypto-aes;85;85;106;114;81;93;105;102;81;85 02:27:08 INFO - PROCESS | 1767 | |11;stanford-crypto-ccm;141;142;146;149;146;149;144;145;201;144 02:27:08 INFO - PROCESS | 1767 | |12;stanford-crypto-pbkdf2;169;170;167;166;169;167;231;179;166;167 02:27:08 INFO - PROCESS | 1767 | |13;stanford-crypto-sha256-iterative;67;68;69;68;70;70;69;69;68;67 02:27:08 INFO - PROCESS | 1767 | __end_tp_report 02:27:08 INFO - PROCESS | 1767 | __start_cc_report 02:27:08 INFO - PROCESS | 1767 | _x_x_mozilla_cycle_collect,982 02:27:08 INFO - PROCESS | 1767 | __end_cc_report 02:27:08 INFO - PROCESS | 1767 | __startTimestamp1482575228406__endTimestamp 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | ------- Summary: start ------- 02:27:08 INFO - PROCESS | 1767 | Number of tests: 14 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | [#0] ai-astar Cycles:10 Average:130.30 Median:123.50 stddev:19.53 (15.8%) stddev-sans-first:19.91 02:27:08 INFO - PROCESS | 1767 | Values: 115.0 115.0 119.0 144.0 173.0 152.0 118.0 125.0 122.0 120.0 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:134.50 stddev:23.49 (17.5%) stddev-sans-first:16.63 02:27:08 INFO - PROCESS | 1767 | Values: 182.0 154.0 114.0 148.0 117.0 130.0 111.0 114.0 139.0 113.0 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | [#2] audio-dft Cycles:10 Average:198.20 Median:199.00 stddev:2.74 (1.4%) stddev-sans-first:2.79 02:27:08 INFO - PROCESS | 1767 | Values: 196.0 199.0 198.0 204.0 194.0 199.0 197.0 196.0 199.0 200.0 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | [#3] audio-fft Cycles:10 Average:67.30 Median:63.00 stddev:11.35 (18.0%) stddev-sans-first:10.72 02:27:08 INFO - PROCESS | 1767 | Values: 82.0 58.0 59.0 88.0 60.0 62.0 64.0 60.0 80.0 60.0 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:80.00 stddev:8.37 (10.5%) stddev-sans-first:8.40 02:27:08 INFO - PROCESS | 1767 | Values: 86.0 73.0 90.0 74.0 70.0 72.0 86.0 69.0 74.0 89.0 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:89.50 stddev:2.75 (3.1%) stddev-sans-first:2.92 02:27:08 INFO - PROCESS | 1767 | Values: 89.0 87.0 90.0 87.0 91.0 87.0 96.0 88.0 90.0 88.0 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | [#6] imaging-darkroom Cycles:10 Average:104.90 Median:95.00 stddev:18.94 (19.9%) stddev-sans-first:19.74 02:27:08 INFO - PROCESS | 1767 | Values: 95.0 95.0 94.0 151.0 125.0 95.0 95.0 96.0 95.0 108.0 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | [#7] imaging-desaturate Cycles:10 Average:107.80 Median:81.00 stddev:26.35 (32.5%) stddev-sans-first:26.85 02:27:08 INFO - PROCESS | 1767 | Values: 87.0 91.0 86.0 105.0 139.0 76.0 144.0 143.0 119.0 88.0 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | [#8] json-parse-financial Cycles:10 Average:58.90 Median:60.00 stddev:1.73 (2.9%) stddev-sans-first:1.80 02:27:08 INFO - PROCESS | 1767 | Values: 58.0 56.0 56.0 59.0 60.0 60.0 59.0 60.0 61.0 60.0 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | [#9] json-stringify-tinderbox Cycles:10 Average:75.70 Median:74.50 stddev:7.83 (10.5%) stddev-sans-first:4.74 02:27:08 INFO - PROCESS | 1767 | Values: 94.0 68.0 84.0 77.0 74.0 70.0 73.0 71.0 75.0 71.0 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | [#10] stanford-crypto-aes Cycles:10 Average:93.70 Median:83.00 stddev:12.07 (14.5%) stddev-sans-first:12.38 02:27:08 INFO - PROCESS | 1767 | Values: 85.0 85.0 106.0 114.0 81.0 93.0 105.0 102.0 81.0 85.0 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | [#11] stanford-crypto-ccm Cycles:10 Average:150.70 Median:146.00 stddev:17.86 (12.2%) stddev-sans-first:18.60 02:27:08 INFO - PROCESS | 1767 | Values: 141.0 142.0 146.0 149.0 146.0 149.0 144.0 145.0 201.0 144.0 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:169.00 stddev:20.01 (11.8%) stddev-sans-first:21.10 02:27:08 INFO - PROCESS | 1767 | Values: 169.0 170.0 167.0 166.0 169.0 167.0 231.0 179.0 166.0 167.0 02:27:08 INFO - PROCESS | 1767 | 02:27:08 INFO - PROCESS | 1767 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:1.08 (1.6%) stddev-sans-first:1.00 02:27:08 INFO - PROCESS | 1767 | Values: 67.0 68.0 69.0 68.0 70.0 70.0 69.0 69.0 68.0 67.0 02:27:08 INFO - PROCESS | 1767 | -------- Summary: end -------- 02:27:08 INFO - PROCESS | 1767 | 02:27:09 INFO - TEST-INFO | 1767: exit 0 02:27:09 INFO - TEST-OK | kraken | took 61193ms 02:27:09 INFO - SUITE-END | took 1178s 02:27:09 INFO - Completed test suite (00:19:38) 02:27:09 INFO - javascript benchmark 02:27:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7057.906843377758, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3344.6655334466554, 3355.547001154843, 3356.4461863792476, 3384.272381437992, 3397.6942075213224, 5073.467285934979, 5373.355197376311, 5662.5186859116975, 5701.173329867167, 5758.913616295759, 5721.713914304279, 5745.195672605828, 5805.216295800065, 5822.592418530705, 5851.970740146304, 5037.319961805164, 5256.842294731164, 5288.233206185101, 5294.894102117955, 5318.813841515548, 5319.734013299339, 5345.465453454663, 5345.625806193568, 5369.543588794957, 5377.274068000809, 5128.769205385766, 5167.173252279636, 5323.441038690939, 5323.467653234665, 5334.386545547273, 5168.664036837631, 5222.712750798704, 5224.947750522484, 5242.423333433319, 5251.658642188246, 5108.463611320795, 5185.714785686786, 5213.296205012312, 5228.92156617651, 5292.311999440087, 5809.970950145264, 5815.767369305223, 5833.679147646878, 5839.357670656224, 5862.413758624146, 6031.733335999402, 6160.938390616081, 6203.534734894856, 6255.655938923357, 6266.811995640138, 6208.906866396963, 6238.750449981995, 6272.78045268418, 6279.905801412982, 6284.402981716729, 5794.797182098607, 5809.302883653989, 5819.359870414251, 5820.912686309709, 5849.64902105875, 6166.383361663798, 6211.627302361872, 6223.253209614875, 6253.96873015632, 6274.498040156776, 6083.66539840313, 6110.072772100112, 6130.111133885562, 6144.801763656044, 6173.536984726164, 5288.788448462018, 6081.783643271364, 6222.564420490613, 6244.562880598314, 6273.811785646438, 4994.950050499521, 5817.418258174149, 6186.350433204449, 6200.968995154995, 6245.843853903598, 4474.6867719259335, 6162.845928851725, 6188.9690551546955, 6203.8138855834395, 6209.037599172136, 4141.565135660712, 4917.803287868445, 4971.801127954841, 4974.204127339608, 4986.476419975923, 4406.3170208617075, 4424.247877860771, 4428.446444194476, 4457.687961842705, 4462.241418958784, 5523.28197334352, 5724.370319264877, 5760.51035661965, 5776.0, 5945.1973983512535, 5184.8185313514605, 6198.0702894566175, 6220.7511699531515, 6262.060690896311, 6288.930881750113, 5727.3699893011735, 5796.246487956623, 5813.215215945877, 5856.238688970407, 5886.352501224862, 5405.243265942771, 5419.620626556182, 5422.267993820863, 5445.918311225335, 5447.673139611636, 5291.3121294231505, 6217.502599792097, 6227.0, 6264.467520260738, 6355.332690067661, 6251.156093927443, 6277.529185311028, 6289.339619339903, 6295.905561416491, 6307.085472606538, 6195.96902015487, 6262.561620686508, 6262.997920332907, 6271.278802937721, 6322.652254126067, 6216.0, 6226.906596400967, 6245.781397651061, 6255.124282600531, 6256.124142619946, 6209.379062093754, 6224.128621993015, 6249.468795152281, 6260.718267677874, 6287.748490060347, 6003.45968862787, 6029.125776762433, 6074.997625391938, 6166.630002199882, 6207.317195108444, 6112.442689633966, 6134.202553667995, 6136.5090792737365, 6140.478059365004, 6142.846428839315, 5870.589058765845, 5965.970170149121, 5986.551008674454, 6002.549808764447, 6003.969980150071, 5902.822915312548, 6025.638461692312, 6121.392004143408, 6134.202553667995, 6176.876462470816, 6025.8192254232445, 6038.818835434944, 6050.062240352752, 6060.393960603904, 6075.513958883191], "value": 5682.779184003215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.01349180213344, 485.75712143928024, 501.6237821633775, 506.3164727617716, 510.56091761085435, 26067.47865042698, 39120.608793912055, 39122.413163802565, 39145.60854391459, 39152.60847391525, 39802.0, 40375.0, 40416.19167616646, 40447.59552404475, 40449.59550404495, 8380.371472139583, 8856.937167539872, 9112.635494580225, 9317.114874086985, 9327.580258888333, 8379.916200837975, 9045.728628141167, 9350.859737103949, 9404.153626173644, 9416.905830941705, 9365.859512107323, 9367.15695587397, 9412.341136120474, 9420.576074076684, 9444.433333999981, 9223.123803238681, 9297.163255307021, 9318.580663870142, 9368.20370268528, 9372.156505914432, 8340.541270230135, 8880.8667869982, 9177.082291770836, 9232.261419086457, 9307.883054033491, 37889.810550947346, 38296.0, 38319.0, 38396.80801596002, 38527.42208866869, 3232.4181647303476, 3374.8143852088124, 3381.9177863083823, 3450.7584469087174, 3896.7272290939645, 28450.288742781388, 28867.989620363187, 29177.854110729524, 29299.267518312, 30459.69540304591, 3140.1835522764018, 3192.0, 3210.96789032109, 3233.3048394595276, 3233.3533293341425, 451028.74485627364, 456159.0, 458915.0, 459625.0, 461034.0, 61285.38714612886, 61677.0, 61723.0, 61767.6911615439, 61917.0, 58745.70627146837, 58969.705151473965, 59224.815503690545, 59390.703046484494, 59582.0, 15937.20313984296, 16087.356505739874, 16302.755458668129, 16306.0, 16379.754303685455, 12584.622461326175, 12610.936945315398, 12661.873381266254, 12662.36688165588, 12675.873241267655, 15516.37934482608, 18477.90761046186, 18480.353187638368, 18879.867207967567, 18949.147288372056, 3194.9840250798597, 7657.349125324298, 7973.401994850294, 7997.840043199103, 8015.278624923785, 709.9680514376865, 770.6647608290411, 887.8845750052452, 951.9195712865809, 1059.86751656043, 26272.868635656698, 28936.565951510744, 29516.0, 29959.40081198364, 30839.229019274248, 1007.1539906478594, 1054.6888667843004, 1061.7398737309409, 1075.9246852720391, 1079.96220132295, 15072.0, 15523.301451434487, 15620.609484762745, 15679.216039197994, 15699.607509812346, 616474.917625409, 619400.0, 625870.0, 626727.0, 627942.0, 57362.7131864338, 57748.71125644345, 57890.84218315694, 57968.420315796306, 58151.41848581545, 1328.754180476615, 1346.1250187378282, 1373.8557451467652, 1376.050525137652, 1376.4150236149596, 29393.0, 29885.551716723832, 29955.0, 30134.69865301319, 30460.93386731454, 50923.490765091876, 51165.72085697887, 51284.487155129464, 51697.0, 51720.448386548465, 625593.0, 626526.0, 628791.0, 630387.0, 631048.0, 26796.59805102886, 27094.72905271001, 27126.321841954108, 27142.72857271402, 27189.456210876066, 45604.543954561355, 45795.771021146014, 46307.07385852331, 46441.53558464372, 47256.0, 37328.25343493169, 38145.2370952585, 39890.0, 41097.0, 42024.0, 38342.041448964, 38837.0, 39337.213255735296, 39774.60225397709, 40004.599954000085, 37858.053548661504, 38418.615813841505, 38442.80778596201, 38597.80701096477, 38855.61144388633, 26878.32804179911, 33903.98288051363, 34410.52663153774, 34917.30165396729, 35206.47190292199, 25551.0, 25582.0, 25679.0, 25972.220833375028, 26041.739582603932], "value": 19580.893573888192, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.784145591917, 357.551273152194, 369.86315063426537, 373.3261463059177, 389.6746216908881, 16588.253528591213, 18294.90852545737, 18685.813141868595, 19311.324103656374, 19695.0, 18069.00620465877, 18620.16209270583, 18791.624167516675, 18799.24803007878, 19297.807021929777, 5154.819581314655, 5508.366537848158, 5741.397153298906, 5873.383294754032, 5902.734376953026, 5540.113581826909, 5599.300087489064, 5688.573356998241, 5723.828285151452, 5730.828075157752, 4553.225951588238, 4810.591099756525, 5118.7714948412295, 5589.636673616223, 5616.9327827712805, 4613.8154473821005, 4897.240927656219, 5583.4974852263285, 5612.540739407763, 5973.820785376446, 5553.194786755897, 5731.484166425, 5748.65508069513, 5768.913466298009, 5783.739731712083, 11706.473208705629, 11999.80001999793, 13064.150830196102, 13131.883789877871, 13173.34133293341, 2222.5665995130958, 2359.575276450238, 2577.9226623201334, 2708.7155848635807, 2928.31184671602, 9769.071938165864, 9817.0, 9872.802543949081, 10355.482225888676, 10362.48187590625, 1695.28797904879, 1806.4038867173801, 1824.14265295311, 1846.796852346241, 1911.8757280776845, 87400.81497962601, 106767.0, 109016.0, 111296.88703112946, 111834.0, 11448.141389395829, 11515.481803318871, 11554.826677599844, 11747.823782643267, 12003.0, 14365.497207597684, 14404.063735857137, 14404.711905762035, 14412.351444185037, 14457.277136143151, 8224.095349511548, 8341.124181960928, 8367.246947774607, 8405.495670259805, 8566.528840913685, 8343.541105239148, 8366.623501942428, 8460.619272132768, 8581.914180858237, 8602.268807151337, 4012.9799351003644, 4417.823287068545, 4540.909181816345, 4554.316852472156, 4559.9772001140445, 1766.5318690547015, 1914.5213696575856, 1926.1524929031182, 1948.6784680527837, 1963.2834015584208, 778.7741554949115, 866.3459088388227, 901.8782464367226, 971.9988411935717, 1002.2354509811598, 17244.913775431043, 18755.062246887876, 20130.59738805245, 20904.79095209059, 21203.36389908305, 882.3646974178581, 895.9641614335353, 900.7567956651667, 939.7227817793768, 984.6455276100598, 8045.597720113971, 10403.687889363331, 10629.946850265855, 10822.971817677308, 10853.565857365775, 386742.0, 458411.70794146485, 520923.39538302063, 526963.0, 529212.0, 62113.68943155255, 81746.0, 106502.93497065085, 114483.0, 136503.0, 797.8563858505524, 799.5282783157965, 946.0917519181526, 986.4673076538588, 993.6671215142864, 15100.0, 18345.541361465537, 18629.627407451502, 18809.341673041377, 19071.427857164308, 44117.0, 54025.91948161093, 54334.45665543294, 55222.0, 55627.0, 78218.0, 80152.0, 80508.0, 80989.19010810052, 80996.19003810122, 6702.966485167738, 7960.084590271961, 8220.83558328842, 8313.501189928624, 8342.040665323322, 9811.509424528887, 12625.242485450903, 12958.611241662766, 13048.0, 13075.803862942254, 10802.405867677353, 11104.61133860311, 11233.606823761129, 11281.7743645125, 11352.886471135183, 5181.611379146654, 8481.957590212009, 9237.383457894975, 9615.56729947168, 10999.34003959765, 11365.715857103638, 11480.368579728196, 11572.0, 11629.47667354988, 11845.0, 6277.874442511215, 7879.487833290817, 8072.425876953937, 8236.711715089943, 8373.665053397795, 9757.51212439361, 10783.6225732099, 11137.220394572301, 11409.25839820409, 11445.48495317696], "value": 8976.963149087022, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.9058211216272, 1484.0, 1644.0053767470679, 1726.3008481564955, 1776.7246076858091, 7510.737124200654, 7793.376529877611, 8036.598170091494, 8490.40567160299, 8508.574571271443, 7948.483348582338, 8245.175482451752, 8270.710525131622, 8395.748127556182, 8416.621252043657, 2118.523332250243, 2340.9531809363802, 2342.051469154995, 2342.3792694935855, 2354.3407845803204, 2312.8612283262974, 2359.893804778789, 2364.7398786133513, 2367.83425160238, 2370.7747763962484, 2285.325828880484, 2287.1880482428746, 2306.6770652108717, 2309.4919117794057, 2312.1560630369877, 2135.4341099608614, 2156.3315372234656, 2184.5958497677902, 2188.0153930731076, 2190.2224710227956, 2229.1529218896876, 2293.724753029647, 2324.1052194904914, 2327.0924339507606, 2338.4387746940783, 7418.406527477789, 8293.129221431784, 8402.78993025173, 8403.0, 8405.033421156522, 5035.2195409711185, 5164.431912489623, 5187.299714538527, 5193.506616871391, 5207.3230480037355, 3233.1270556949726, 3319.6348401675796, 3544.3088597723454, 3546.716262698978, 3549.7870127792416, 1048.1562342314435, 1057.8413238014282, 1086.6577028236081, 1097.9725506862392, 1106.8505751723494, 10860.402677852715, 10904.127669786398, 10911.563537458493, 10914.727131821768, 10995.505202265836, 4499.235130027903, 4566.1552612766745, 4725.621950243973, 4763.618910487153, 4780.402449693788, 7110.146782386147, 9006.964199117185, 9349.065093490732, 9349.813003739888, 9364.812703745887, 5178.97410512945, 5191.195364718475, 5196.142636465017, 5196.76614552346, 5214.374275087014, 3990.4014397840556, 4260.041490664576, 4274.871753847389, 4735.644826638016, 4774.641901857374, 3332.9333413332083, 3382.9323413531592, 3753.60587138352, 3772.905677358088, 3782.451544526083, 1104.1718710966775, 1267.2776517385187, 1282.313962030328, 1285.4536821850677, 1290.6579756364572, 784.3842583571983, 867.1805144138855, 884.4698671298561, 909.940853844498, 915.0849150849151, 4700.6944548604915, 5467.863303417367, 5515.834524964257, 5531.695756733418, 5534.972325138348, 436.7292278787172, 438.72141190343723, 442.11797464058765, 917.8485549884327, 962.2879069488528, 3444.896653100411, 3709.8516059357326, 3722.571904231048, 3729.589745128034, 3816.637419445148, 8106.47307924983, 9911.851322230172, 9968.651097211563, 10049.196064314838, 10100.696979090639, 9957.452340121217, 9974.501274936225, 9975.800483990424, 9987.750306242257, 10018.549165287579, 7508.511946723445, 7725.459217854808, 7788.065432148179, 7807.570583617956, 7904.28861402465, 5441.483059109379, 5470.781168753205, 5517.213807027586, 5561.443855614568, 5681.431856814285, 8170.182981701782, 9348.53257337144, 9391.154796068387, 9391.436513809193, 9396.76508087276, 9413.576389062646, 9455.905440945502, 9490.193333566724, 9496.762580935256, 9584.712458626253, 6580.21037475506, 6813.148356455443, 6933.306669333027, 7052.0, 7255.2019277880445, 7784.92215077842, 7940.444168908121, 7955.403344749283, 7983.800404989922, 8013.719519817012, 6940.340667636567, 7074.858502829811, 7120.60836653989, 7121.287871212838, 7168.354848063699, 6929.653517324215, 6937.895931561132, 6969.992244516338, 7044.119485064367, 7060.3292687194635, 6636.634985075867, 6655.900161497679, 6698.66506674674, 6722.731090756315, 6733.225679046972, 4785.521447855187, 4807.879803004953, 4837.37114175155, 4845.079434907498, 4863.635227358033, 3921.745086569364, 4754.21555443348, 4770.880727981828, 4885.65800393969, 4994.0], "value": 4324.994958838911, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.561142925614, 299.694311801962, 303.28878779262647, 306.89412152807273, 310.6458637153644, 4283.614474697277, 4835.903281934364, 5360.855657736907, 5385.838424847261, 5462.836114916554, 5018.573421259197, 5202.4537423570455, 5371.0, 5457.808976685817, 5458.9454105458935, 3391.6269210386777, 4125.575772109157, 4136.711314504961, 4141.64795992341, 4141.937870931938, 3993.5008123984503, 4119.567445418233, 4130.463039804821, 4180.0, 4215.768132752697, 3263.037403965836, 3892.9099852041472, 4091.7954102294916, 4105.003347556286, 4162.292410290258, 3258.14911952042, 3602.963970360289, 4113.9794301028305, 4158.397032430311, 4168.583141685807, 3620.5698748994264, 3627.927441451156, 3634.1290380599294, 3635.745497815154, 3636.563612366533, 5035.244713292998, 5244.737763111867, 5264.42091369949, 5286.207068939651, 5347.839564813062, 4682.742449165295, 4699.835505757282, 4708.105459962596, 4711.057788442326, 4718.4809670936165, 5632.7746890124345, 5645.657052323322, 5680.6591604503865, 5683.687397193153, 5708.743106560215, 3413.607435144965, 3576.0, 3818.0, 3857.324968130594, 3958.6239307265764, 4764.714117153017, 4984.576311013569, 5150.021495915764, 6042.398764327457, 6152.72349230783, 3461.3769521486374, 3921.3529767588598, 3926.1131479834758, 3931.6264954829244, 3948.368261078214, 3714.851405943786, 3944.3294639911283, 3959.742616729903, 3977.622125898093, 4001.8199181036925, 4034.1326614777963, 4622.6764126510825, 4647.513580234626, 4676.942145456056, 4713.269443236304, 5058.747062646853, 5709.628874123252, 5723.77104915807, 5824.388439213906, 5892.351841297454, 1097.6706987903597, 1223.07046644549, 1255.2029461291963, 1261.2153802369544, 1269.9682507937375, 716.4411758828019, 767.458941446279, 848.6308455821737, 869.3914260018013, 883.3198437203317, 4591.265397536445, 4816.903661926742, 4884.3650325457465, 5058.645894787403, 5154.407243167084, 4988.7777494514075, 5402.702851343214, 5470.699111548824, 5487.862803429946, 5518.179000929684, 4232.793673759561, 4264.893377665583, 4566.977165114153, 4759.309900064472, 5091.937765205098, 2693.6666350156797, 2698.7031426543062, 2812.0, 2928.3996780659913, 2935.163478408686, 4832.975835120802, 5560.4161563035295, 5670.603057785916, 5731.01986966775, 5798.1302804579655, 5500.67983683913, 5528.419515950848, 5570.888582228333, 5714.2571465710025, 6169.691515424211, 6164.845878853064, 6219.045953172297, 6235.75056997715, 6252.874942501216, 6265.248170219603, 5778.451047150514, 6001.0, 6017.789377371771, 6027.397260273938, 6053.334133245427, 4928.679635823803, 5139.8458046258675, 5150.381954165524, 5888.411158884077, 5923.881522369615, 5230.953809238244, 5958.553108516792, 6153.907691384722, 6281.246250449975, 6333.398327158912, 3841.846326146923, 3890.7860067696547, 3949.3878448840474, 3963.5441924177935, 3972.463717398114, 5146.716930568705, 5267.552258058108, 5930.15352630731, 5939.67331796738, 5990.460858522754, 4141.544430112625, 4648.86053418398, 4672.8551504881525, 5010.06709732858, 5797.681127538026, 4768.809247630056, 5309.36287645485, 5743.023685973311, 5779.595428319977, 5792.275965504312, 4962.602991760578, 5309.283246761638, 5681.630694004876, 5681.744321505459, 5684.346300175367, 4976.0, 5675.155574438407, 5757.7832534475365, 5784.305883294031, 5795.478406943395, 4239.088595951762, 5139.511746384088, 5392.6225164239695, 5661.980843448054, 5756.165356023437], "value": 4081.103668845897, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [651.8794023105722, 683.2859661653572, 713.8857782754759, 717.8348979734665, 771.7761849063771, 13837.238951857657, 14711.852881471183, 14719.852801471981, 14726.410943562272, 14759.631009224775, 13277.800832987514, 13322.666933326671, 13325.866741332584, 13393.0, 13407.530736424227, 642.6401215319413, 664.1697877652933, 772.4284029817936, 783.6943591999125, 814.1248158229897, 800.8278220182659, 806.8628333183373, 809.6842231529708, 810.3638643664724, 811.4765975945512, 844.7845799321165, 845.8900342955438, 858.4420126917491, 861.732862812527, 862.2886118951858, 843.7510934274343, 848.8854004709347, 849.1762989899818, 851.3274513134617, 851.5699571716306, 631.0912286307703, 631.6589041917405, 634.4163369699887, 635.2757856044113, 635.5900444213456, 11721.472533736003, 11771.882281177164, 13846.238456884867, 13947.58157255284, 13962.0, 12950.48198072076, 13391.866081339256, 13453.865461345456, 13454.865451345557, 13462.865371346259, 10826.891731082667, 10949.562017519369, 11310.491027903681, 11324.603638872682, 11508.942455287754, 3172.254520187754, 3434.4161492546077, 3660.2862441823854, 3774.452704357853, 3781.054736315921, 87658.0, 90371.09628903758, 90633.54683226607, 91092.0, 91325.99999999933, 10034.64878729241, 10169.847452288222, 10288.228382871315, 10409.739756505996, 10419.218558607983, 19933.60132797365, 19994.70007949882, 20129.194832206842, 20355.491112722004, 20414.08136633855, 7510.924890751023, 7559.1684914659345, 7635.465517413728, 7717.691292348355, 7766.9902912621, 4248.065425606362, 4310.784460776948, 4394.384786129945, 4396.626286765661, 4406.537313582027, 2950.9704902950693, 3336.7497437691786, 3425.503302021193, 3456.1186897341017, 3462.1344663834043, 606.9817905462843, 625.7465726380823, 631.2614241337646, 633.762339122829, 646.7445359083135, 589.2310534752104, 632.8516573858311, 635.2155088465742, 647.7538535356534, 653.9934600654028, 8005.0, 8351.958240208882, 8712.607932643046, 8831.470111793184, 9013.414128081653, 534.7005676820993, 619.4796371048251, 621.2886245249255, 649.0070192605322, 666.3369946902862, 4070.1859628074503, 4334.848280310174, 4356.869293921188, 4376.934345984812, 4399.0471880609775, 75495.0, 79517.60241198757, 84147.5792621033, 112355.43822280836, 114458.28312575318, 61651.691741541006, 66428.67142657119, 85433.57283213669, 103545.0, 104113.0, 56849.0, 87884.56057719671, 89398.94089136564, 92017.0, 92859.5357023224, 7567.356774674215, 8435.198656127657, 8698.91301086981, 9128.908710912805, 9431.952840235756, 39423.802880985415, 39740.0, 39744.20511589694, 44150.0, 46560.0, 54220.728896355264, 67130.0, 69280.65359673169, 71077.64461177662, 78045.0, 12084.335361555199, 12153.817692734792, 12214.450349734461, 12305.384730763604, 12307.26156430617, 13299.135556188818, 13893.861061389256, 13934.075129249868, 13986.0, 14293.57119286423, 12392.132550721362, 12570.685732856751, 13028.739425211254, 13030.609081727564, 13258.801117983045, 9662.371945823781, 10276.588936442758, 10643.627473038407, 12571.0, 13061.608151755463, 10523.158147348347, 12059.336736479578, 12392.380380980734, 12786.616401507597, 12902.483900643869, 10156.593736250466, 10486.94756526243, 10641.627543035956, 11125.0, 11147.163962702667, 10616.416097114732, 10900.454977251264, 10945.507452164513, 11157.776844463227, 11180.993710566088], "value": 7010.080649023576, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 119.0, 144.0, 173.0, 152.0, 118.0, 125.0, 122.0, 120.0], "value": 130.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 154.0, 114.0, 148.0, 117.0, 130.0, 111.0, 114.0, 139.0, 113.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 198.0, 204.0, 194.0, 199.0, 197.0, 196.0, 199.0, 200.0], "value": 198.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 59.0, 88.0, 60.0, 62.0, 64.0, 60.0, 80.0, 60.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 73.0, 90.0, 74.0, 70.0, 72.0, 86.0, 69.0, 74.0, 89.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 87.0, 91.0, 87.0, 96.0, 88.0, 90.0, 88.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 151.0, 125.0, 95.0, 95.0, 96.0, 95.0, 108.0], "value": 104.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 91.0, 86.0, 105.0, 139.0, 76.0, 144.0, 143.0, 119.0, 88.0], "value": 107.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 56.0, 59.0, 60.0, 60.0, 59.0, 60.0, 61.0, 60.0], "value": 58.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 68.0, 84.0, 77.0, 74.0, 70.0, 73.0, 71.0, 75.0, 71.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 106.0, 114.0, 81.0, 93.0, 105.0, 102.0, 81.0, 85.0], "value": 93.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 142.0, 146.0, 149.0, 146.0, 149.0, 144.0, 145.0, 201.0, 144.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 167.0, 166.0, 169.0, 167.0, 231.0, 179.0, 166.0, 167.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 68.0, 70.0, 70.0, 69.0, 69.0, 68.0, 67.0], "value": 68.5, "unit": "score"}]}]} 02:27:09 INFO - Return code: 0 02:27:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:27:09 ERROR - # TBPL SUCCESS # 02:27:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:27:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:27:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:27:09 INFO - 2016-12-24 02:27:09.754 screenresolution[1772:21878] starting screenresolution argv=screenresolution get 02:27:09 INFO - 2016-12-24 02:27:09.756 screenresolution[1772:21878] Display 0: 1600x1200x32@60 02:27:09 INFO - 2016-12-24 02:27:09.762 screenresolution[1773:21881] starting screenresolution argv=screenresolution list 02:27:09 INFO - Available Modes on Display 0 02:27:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:27:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:27:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:27:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:27:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:27:09 INFO - Intel Iris: 02:27:09 INFO - Chipset Model: Intel Iris 02:27:09 INFO - Type: GPU 02:27:09 INFO - Bus: Built-In 02:27:09 INFO - VRAM (Dynamic, Max): 1536 MB 02:27:09 INFO - Vendor: Intel (0x8086) 02:27:09 INFO - Device ID: 0x0a2e 02:27:09 INFO - Revision ID: 0x0009 02:27:09 INFO - Displays: 02:27:09 INFO - Display: 02:27:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:27:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:27:09 INFO - Display Serial Number: 1600x1200 60 02:27:09 INFO - Main Display: Yes 02:27:09 INFO - Mirror: Off 02:27:09 INFO - Online: Yes 02:27:09 INFO - Rotation: Supported 02:27:10 INFO - Return code: 0 02:27:10 INFO - Running post-action listener: _resource_record_post_action 02:27:10 INFO - [mozharness: 2016-12-24 10:27:10.002834Z] Finished run-tests step (success) 02:27:10 INFO - Running post-run listener: _resource_record_post_run 02:27:10 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 34377728; Write bytes: 663285248; Read time: 316; Write time: 1850 02:27:10 INFO - TinderboxPrint: CPU usage
54.4% 02:27:10 INFO - TinderboxPrint: I/O read bytes / time
34,377,728 / 316 02:27:10 INFO - TinderboxPrint: I/O write bytes / time
663,285,248 / 1,850 02:27:10 INFO - TinderboxPrint: CPU idle
2,209.8 (45.6%) 02:27:10 INFO - TinderboxPrint: CPU system
984.7 (20.3%) 02:27:10 INFO - TinderboxPrint: CPU user
1,651.2 (34.1%) 02:27:10 INFO - TinderboxPrint: Swap in / out
379,142,144 / 0 02:27:10 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 343785984; Write bytes: 342464000; Read time: 26825; Write time: 689 02:27:10 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 32522240; Write bytes: 309602304; Read time: 296; Write time: 1126 02:27:10 INFO - Running post-run listener: _upload_blobber_files 02:27:10 INFO - Blob upload gear active. 02:27:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:27:10 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:27:10 INFO - (blobuploader) - INFO - Open directory for files ... 02:27:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:27:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:27:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:27:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:27:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:27:12 INFO - (blobuploader) - INFO - Done attempting. 02:27:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:27:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:27:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:27:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:27:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:27:13 INFO - (blobuploader) - INFO - Done attempting. 02:27:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:27:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:27:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:27:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:27:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:27:15 INFO - (blobuploader) - INFO - Done attempting. 02:27:15 INFO - (blobuploader) - INFO - Iteration through files over. 02:27:15 INFO - Return code: 0 02:27:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:27:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:27:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04593e47a1ecbecafe1fa3ae918c14278c7005ef36511d9af520257b16d0229edee1548a0e6ad17df068d6565ef9291cd219052bc988e0abca1788ba23934085", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33691f9f53b19b26659f48eaf02fc0f1dfbabc39231ad08f486e1cf90590a1c1fae3bca0d91a9285ae14f8ca2e8ff191e7b322f9bc6bf5aa84aa6da892ddc4bf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a98e6fe64001043ff884c23e26da69eaae5895362bb84b04f06a04a787378a80919443d2484ae40e8524863b1a0ca8e9a7710964ce7205ac39822f0f7ec631a2"} 02:27:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:27:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:27:15 INFO - Contents: 02:27:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04593e47a1ecbecafe1fa3ae918c14278c7005ef36511d9af520257b16d0229edee1548a0e6ad17df068d6565ef9291cd219052bc988e0abca1788ba23934085", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33691f9f53b19b26659f48eaf02fc0f1dfbabc39231ad08f486e1cf90590a1c1fae3bca0d91a9285ae14f8ca2e8ff191e7b322f9bc6bf5aa84aa6da892ddc4bf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a98e6fe64001043ff884c23e26da69eaae5895362bb84b04f06a04a787378a80919443d2484ae40e8524863b1a0ca8e9a7710964ce7205ac39822f0f7ec631a2"} 02:27:15 INFO - Running post-run listener: copy_logs_to_upload_dir 02:27:15 INFO - Copying logs to upload dir... 02:27:15 INFO - mkdir: /builds/slave/test/build/upload/logs 02:27:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.403310 ========= master_lag: 1.29 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-24 02:27:16.510874) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-24 02:27:16.514464) ========= 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.o9epAFFiIK/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.gXfmfiRnh3/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04593e47a1ecbecafe1fa3ae918c14278c7005ef36511d9af520257b16d0229edee1548a0e6ad17df068d6565ef9291cd219052bc988e0abca1788ba23934085", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33691f9f53b19b26659f48eaf02fc0f1dfbabc39231ad08f486e1cf90590a1c1fae3bca0d91a9285ae14f8ca2e8ff191e7b322f9bc6bf5aa84aa6da892ddc4bf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a98e6fe64001043ff884c23e26da69eaae5895362bb84b04f06a04a787378a80919443d2484ae40e8524863b1a0ca8e9a7710964ce7205ac39822f0f7ec631a2"} build_url:https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011004 build_url: 'https://queue.taskcluster.net/v1/task/PnDo9oMXTguEX8NpUiZkBQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04593e47a1ecbecafe1fa3ae918c14278c7005ef36511d9af520257b16d0229edee1548a0e6ad17df068d6565ef9291cd219052bc988e0abca1788ba23934085", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33691f9f53b19b26659f48eaf02fc0f1dfbabc39231ad08f486e1cf90590a1c1fae3bca0d91a9285ae14f8ca2e8ff191e7b322f9bc6bf5aa84aa6da892ddc4bf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a98e6fe64001043ff884c23e26da69eaae5895362bb84b04f06a04a787378a80919443d2484ae40e8524863b1a0ca8e9a7710964ce7205ac39822f0f7ec631a2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-24 02:27:16.552273) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 02:27:16.552645) ========= 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.o9epAFFiIK/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.gXfmfiRnh3/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.005308 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 02:27:16.600927) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-24 02:27:16.601271) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-24 02:27:16.601596) ========= ========= Total master_lag: 1.49 =========