builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0246 starttime: 1489774020.05 results: success (0) buildid: 20170317090120 builduid: d5f9bc6fb65543dda1fecf1f52e7de66 revision: bc5fff53646952aad7049f86e0340f919a8b5719 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:00.045938) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:00.046435) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:00.046757) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:00.074595) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:00.075503) ========= 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.aAriOKVMGE/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.ioE07iaeRg/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-17 11:07:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 35.6M=0s 2017-03-17 11:07:00 (35.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105381 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:00.208739) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:00.209067) ========= 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.aAriOKVMGE/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.ioE07iaeRg/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.026681 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:00.261075) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-17 11:07:00.261499) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev bc5fff53646952aad7049f86e0340f919a8b5719 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev bc5fff53646952aad7049f86e0340f919a8b5719 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aAriOKVMGE/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.ioE07iaeRg/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-17 11:07:00,339 truncating revision to first 12 chars 2017-03-17 11:07:00,339 Setting DEBUG logging. 2017-03-17 11:07:00,339 attempt 1/10 2017-03-17 11:07:00,339 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/bc5fff536469?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-17 11:07:01,450 unpacking tar archive at: pine-bc5fff536469/testing/mozharness/ program finished with exit code 0 elapsedTime=1.384707 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-17 11:07:01.672924) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:01.673553) ========= script_repo_revision: bc5fff53646952aad7049f86e0340f919a8b5719 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:01.674340) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:01.674887) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:01.696814) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-17 11:07:01.697533) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aAriOKVMGE/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.ioE07iaeRg/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 11:07:01 INFO - MultiFileLogger online at 20170317 11:07:01 in /builds/slave/test 11:07:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 11:07:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:07:01 INFO - {'append_to_log': False, 11:07:01 INFO - 'base_work_dir': '/builds/slave/test', 11:07:01 INFO - 'blob_upload_branch': 'Pine', 11:07:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:07:01 INFO - 'branch': 'Pine', 11:07:01 INFO - 'buildbot_json_path': 'buildprops.json', 11:07:01 INFO - 'config_files': ('talos/mac_config.py',), 11:07:01 INFO - 'default_actions': ('clobber', 11:07:01 INFO - 'read-buildbot-config', 11:07:01 INFO - 'download-and-extract', 11:07:01 INFO - 'populate-webroot', 11:07:01 INFO - 'create-virtualenv', 11:07:01 INFO - 'install', 11:07:01 INFO - 'run-tests'), 11:07:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:07:01 INFO - 'download_minidump_stackwalk': True, 11:07:01 INFO - 'download_symbols': 'ondemand', 11:07:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:07:01 INFO - 'tooltool.py': '/tools/tooltool.py', 11:07:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:07:01 INFO - '/tools/misc-python/virtualenv.py')}, 11:07:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:07:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:07:01 INFO - 'gecko_profile': False, 11:07:01 INFO - 'gecko_profile_interval': 0, 11:07:01 INFO - 'installer_path': 'installer.exe', 11:07:01 INFO - 'log_level': 'info', 11:07:01 INFO - 'log_name': 'talos', 11:07:01 INFO - 'log_to_console': True, 11:07:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:07:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:07:01 INFO - 'opt_config_files': (), 11:07:01 INFO - 'pip_index': False, 11:07:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:07:01 INFO - 'cmd': ('bash', 11:07:01 INFO - '-c', 11:07:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:07:01 INFO - 'enabled': True, 11:07:01 INFO - 'halt_on_failure': False, 11:07:01 INFO - 'name': 'check_screen_resolution'},), 11:07:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:07:01 INFO - 'cmd': ('bash', 11:07:01 INFO - '-c', 11:07:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:07:01 INFO - 'enabled': True, 11:07:01 INFO - 'halt_on_failure': False, 11:07:01 INFO - 'name': 'check_screen_resolution'},), 11:07:01 INFO - 'run_cmd_checks_enabled': True, 11:07:01 INFO - 'suite': 'dromaeojs-e10s', 11:07:01 INFO - 'system_bits': '32', 11:07:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:07:01 INFO - 'title': 't-yosemite-r7-0246', 11:07:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:07:01 INFO - 'use_talos_json': True, 11:07:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:07:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:07:01 INFO - 'work_dir': 'build'} 11:07:01 INFO - [mozharness: 2017-03-17 18:07:01.880799Z] Running clobber step. 11:07:01 INFO - Running pre-action listener: _resource_record_pre_action 11:07:01 INFO - Running main action method: clobber 11:07:01 INFO - rmtree: /builds/slave/test/build 11:07:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:07:06 INFO - Running post-action listener: _resource_record_post_action 11:07:06 INFO - [mozharness: 2017-03-17 18:07:06.702668Z] Finished clobber step (success) 11:07:06 INFO - [mozharness: 2017-03-17 18:07:06.702794Z] Running read-buildbot-config step. 11:07:06 INFO - Running pre-action listener: _resource_record_pre_action 11:07:06 INFO - Running main action method: read_buildbot_config 11:07:06 INFO - Using buildbot properties: 11:07:06 INFO - { 11:07:06 INFO - "project": "", 11:07:06 INFO - "product": "firefox", 11:07:06 INFO - "script_repo_revision": "default", 11:07:06 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 11:07:06 INFO - "repository": "", 11:07:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 11:07:06 INFO - "buildid": "20170317090120", 11:07:06 INFO - "pgo_build": "False", 11:07:06 INFO - "basedir": "/builds/slave/test", 11:07:06 INFO - "buildnumber": 2, 11:07:06 INFO - "slavename": "t-yosemite-r7-0246", 11:07:06 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 11:07:06 INFO - "platform": "yosemite_r7", 11:07:06 INFO - "scheduler": "tests-pine-macosx64-talos", 11:07:06 INFO - "branch": "pine", 11:07:06 INFO - "repo_path": "projects/pine", 11:07:06 INFO - "revision": "bc5fff53646952aad7049f86e0340f919a8b5719", 11:07:06 INFO - "stage_platform": "macosx64", 11:07:06 INFO - "builduid": "d5f9bc6fb65543dda1fecf1f52e7de66", 11:07:06 INFO - "slavebuilddir": "test" 11:07:06 INFO - } 11:07:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 11:07:06 INFO - Running post-action listener: _resource_record_post_action 11:07:06 INFO - [mozharness: 2017-03-17 18:07:06.705989Z] Finished read-buildbot-config step (success) 11:07:06 INFO - [mozharness: 2017-03-17 18:07:06.706109Z] Running download-and-extract step. 11:07:06 INFO - Running pre-action listener: _resource_record_pre_action 11:07:06 INFO - Running main action method: download_and_extract 11:07:06 INFO - mkdir: /builds/slave/test/build/tests 11:07:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:06 INFO - trying https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 11:07:06 INFO - Downloading https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:07:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 11:07:07 INFO - Downloaded 1325 bytes. 11:07:07 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:07:07 INFO - Using the following test package requirements: 11:07:07 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:07 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 11:07:07 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:07:07 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:07 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 11:07:07 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:07 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 11:07:07 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:07 INFO - u'jsshell-mac.zip'], 11:07:07 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:07 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 11:07:07 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:07:07 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:07 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 11:07:07 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:07 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 11:07:07 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:07 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 11:07:07 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:07 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 11:07:07 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:07:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:07:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:07:07 INFO - Fetch https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:07:08 INFO - Expected file size: 52473014 11:07:08 INFO - Obtained file size: 52473014 11:07:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:07:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:07:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:07:25 INFO - Fetch https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:07:28 INFO - Expected file size: 52473014 11:07:28 INFO - Obtained file size: 52473014 11:07:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 11:07:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:07:43 INFO - Fetch https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 11:07:45 INFO - Expected file size: 14404452 11:07:45 INFO - Obtained file size: 14404452 11:07:45 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')]} 11:07:45 INFO - trying https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:07:45 INFO - Downloading https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 11:07:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 11:07:47 INFO - Downloaded 75083433 bytes. 11:07:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:07:47 INFO - mkdir: /builds/slave/test/properties 11:07:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:07:47 INFO - Writing to file /builds/slave/test/properties/build_url 11:07:47 INFO - Contents: 11:07:47 INFO - build_url:https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:07:48 INFO - Running post-action listener: _resource_record_post_action 11:07:48 INFO - Running post-action listener: set_extra_try_arguments 11:07:48 INFO - [mozharness: 2017-03-17 18:07:48.414808Z] Finished download-and-extract step (success) 11:07:48 INFO - [mozharness: 2017-03-17 18:07:48.414920Z] Running populate-webroot step. 11:07:48 INFO - Running pre-action listener: _resource_record_pre_action 11:07:48 INFO - Running main action method: populate_webroot 11:07:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:07:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:07:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:07:48 INFO - '--tppagecycles', 11:07:48 INFO - '7'], 11:07:48 INFO - 'tests': ['tsvgm']}}, 11:07:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:07:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:07:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:07:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:07:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:07:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:07:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:07:48 INFO - 'talos_options': ['--disable-e10s'], 11:07:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:07:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:07:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:07:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:07:48 INFO - 'talos_options': ['--disable-e10s'], 11:07:48 INFO - 'tests': ['damp', 'tps']}, 11:07:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:07:48 INFO - 'tests': ['damp', 'tps']}, 11:07:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:07:48 INFO - 'tests': ['dromaeo_dom']}, 11:07:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:07:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:07:48 INFO - 'tests': ['basic_compositor_video']}, 11:07:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:07:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:07:48 INFO - 'tests': ['a11yr', 11:07:48 INFO - 'ts_paint', 11:07:48 INFO - 'tpaint', 11:07:48 INFO - 'sessionrestore', 11:07:48 INFO - 'sessionrestore_no_auto_restore', 11:07:48 INFO - 'tabpaint']}, 11:07:48 INFO - 'other-e10s': {'tests': ['a11yr', 11:07:48 INFO - 'ts_paint', 11:07:48 INFO - 'tpaint', 11:07:48 INFO - 'sessionrestore', 11:07:48 INFO - 'sessionrestore_no_auto_restore', 11:07:48 INFO - 'tabpaint']}, 11:07:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:07:48 INFO - 'ts_paint', 11:07:48 INFO - 'tpaint', 11:07:48 INFO - 'sessionrestore', 11:07:48 INFO - 'sessionrestore_no_auto_restore', 11:07:48 INFO - 'tabpaint']}, 11:07:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:07:48 INFO - 'ts_paint', 11:07:48 INFO - 'tpaint', 11:07:48 INFO - 'sessionrestore', 11:07:48 INFO - 'sessionrestore_no_auto_restore', 11:07:48 INFO - 'tabpaint']}, 11:07:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:07:48 INFO - 'tests': ['a11yr', 11:07:48 INFO - 'ts_paint', 11:07:48 INFO - 'tpaint', 11:07:48 INFO - 'sessionrestore', 11:07:48 INFO - 'sessionrestore_no_auto_restore', 11:07:48 INFO - 'tabpaint']}, 11:07:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:07:48 INFO - 'tests': ['a11yr', 11:07:48 INFO - 'ts_paint', 11:07:48 INFO - 'tpaint', 11:07:48 INFO - 'sessionrestore', 11:07:48 INFO - 'sessionrestore_no_auto_restore', 11:07:48 INFO - 'tabpaint']}, 11:07:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:07:48 INFO - 'tests': ['tsvgx', 11:07:48 INFO - 'tsvgr_opacity', 11:07:48 INFO - 'tart', 11:07:48 INFO - 'tscrollx', 11:07:48 INFO - 'cart', 11:07:48 INFO - 'tsvg_static']}, 11:07:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:07:48 INFO - 'tsvgr_opacity', 11:07:48 INFO - 'tart', 11:07:48 INFO - 'tscrollx', 11:07:48 INFO - 'cart', 11:07:48 INFO - 'tsvg_static']}, 11:07:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:07:48 INFO - 'talos_options': ['--disable-e10s'], 11:07:48 INFO - 'tests': ['tp5o']}, 11:07:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:07:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:07:48 INFO - 'talos_options': ['--disable-e10s', 11:07:48 INFO - '--xperf_path', 11:07:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:07:48 INFO - 'tests': ['tp5n']}, 11:07:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:07:48 INFO - 'talos_options': ['--xperf_path', 11:07:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:07:48 INFO - 'tests': ['tp5n']}}, 11:07:48 INFO - 'talos.zip': {'path': '', 11:07:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:07:48 INFO - Running post-action listener: _resource_record_post_action 11:07:48 INFO - [mozharness: 2017-03-17 18:07:48.433301Z] Finished populate-webroot step (success) 11:07:48 INFO - [mozharness: 2017-03-17 18:07:48.433415Z] Running create-virtualenv step. 11:07:48 INFO - Running pre-action listener: _resource_record_pre_action 11:07:48 INFO - Running main action method: create_virtualenv 11:07:48 INFO - Creating virtualenv /builds/slave/test/build/venv 11:07:48 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 11:07:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:07:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:07:48 INFO - Using real prefix '/tools/python27' 11:07:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:07:49 INFO - Installing distribute.............................................................................................................................................................................................done. 11:07:52 INFO - Installing pip.................done. 11:07:52 INFO - Return code: 0 11:07:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:07:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:07:52 INFO - Reading from file tmpfile_stdout 11:07:52 INFO - Output received: 11:07:52 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) 11:07:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:07: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')]} 11:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07: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')]} 11:07:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:52 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 0x108a28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3f1591600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108806d30>, '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 0x10872ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fbbe8>, '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.aAriOKVMGE/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.ioE07iaeRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 11:07:52 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 11:07:52 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 11:07:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAriOKVMGE/Render', 11:07:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:07:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:07:52 INFO - 'HOME': '/Users/cltbld', 11:07:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:07:52 INFO - 'LOGNAME': 'cltbld', 11:07:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:52 INFO - 'MOZ_NO_REMOTE': '1', 11:07:52 INFO - 'NO_EM_RESTART': '1', 11:07:52 INFO - 'PAGER': '/bin/cat', 11:07:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:07:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:52 INFO - 'PWD': '/builds/slave/test', 11:07:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:07:52 INFO - 'SHELL': '/bin/bash', 11:07:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ioE07iaeRg/Listeners', 11:07:52 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 11:07:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:07:52 INFO - 'USER': 'cltbld', 11:07:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:07:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:07:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:52 INFO - 'XPC_FLAGS': '0x0', 11:07:52 INFO - 'XPC_SERVICE_NAME': '0', 11:07:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:07:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:52 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 11:07:52 INFO - Cleaning up... 11:07:52 INFO - Return code: 0 11:07:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:07: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')]} 11:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07: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')]} 11:07:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:52 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 0x108a28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3f1591600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108806d30>, '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 0x10872ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fbbe8>, '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.aAriOKVMGE/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.ioE07iaeRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 11:07:52 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 11:07:52 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 11:07:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAriOKVMGE/Render', 11:07:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:07:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:07:52 INFO - 'HOME': '/Users/cltbld', 11:07:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:07:52 INFO - 'LOGNAME': 'cltbld', 11:07:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:52 INFO - 'MOZ_NO_REMOTE': '1', 11:07:52 INFO - 'NO_EM_RESTART': '1', 11:07:52 INFO - 'PAGER': '/bin/cat', 11:07:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:07:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:52 INFO - 'PWD': '/builds/slave/test', 11:07:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:07:52 INFO - 'SHELL': '/bin/bash', 11:07:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ioE07iaeRg/Listeners', 11:07:52 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 11:07:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:07:52 INFO - 'USER': 'cltbld', 11:07:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:07:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:07:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:52 INFO - 'XPC_FLAGS': '0x0', 11:07:52 INFO - 'XPC_SERVICE_NAME': '0', 11:07:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:07:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:52 INFO - Downloading/unpacking psutil>=3.1.1 11:07:52 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 11:07:52 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 11:07:52 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 11:07:52 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 11:07:52 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 11:07:52 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 11:07:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:07:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:07:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:07:56 INFO - Installing collected packages: psutil 11:07:56 INFO - Running setup.py install for psutil 11:07:56 INFO - building 'psutil._psutil_osx' extension 11:07:56 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 11:07:57 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 11:07:57 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 11:07:57 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 11:07:57 INFO - building 'psutil._psutil_posix' extension 11:07:57 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 11:07:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:07:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:07:57 INFO - ^ 11:07:57 INFO - 1 warning generated. 11:07:57 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 11:07:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:07:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:07:57 INFO - Successfully installed psutil 11:07:57 INFO - Cleaning up... 11:07:57 INFO - Return code: 0 11:07:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:07: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')]} 11:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07: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')]} 11:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3f1591600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108806d30>, '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 0x10872ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fbbe8>, '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.aAriOKVMGE/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.ioE07iaeRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 11:07:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:07:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAriOKVMGE/Render', 11:07:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:07:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:07:58 INFO - 'HOME': '/Users/cltbld', 11:07:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:07:58 INFO - 'LOGNAME': 'cltbld', 11:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:58 INFO - 'MOZ_NO_REMOTE': '1', 11:07:58 INFO - 'NO_EM_RESTART': '1', 11:07:58 INFO - 'PAGER': '/bin/cat', 11:07:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:58 INFO - 'PWD': '/builds/slave/test', 11:07:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:07:58 INFO - 'SHELL': '/bin/bash', 11:07:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ioE07iaeRg/Listeners', 11:07:58 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 11:07:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:07:58 INFO - 'USER': 'cltbld', 11:07:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:07:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:58 INFO - 'XPC_FLAGS': '0x0', 11:07:58 INFO - 'XPC_SERVICE_NAME': '0', 11:07:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:07:58 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 11:07:58 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 11:07:58 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 11:07:58 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 11:07:58 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 11:07:58 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 11:08:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:08:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:08:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:08:01 INFO - Installing collected packages: mozsystemmonitor 11:08:01 INFO - Running setup.py install for mozsystemmonitor 11:08:01 INFO - Successfully installed mozsystemmonitor 11:08:01 INFO - Cleaning up... 11:08:02 INFO - Return code: 0 11:08:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:02 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 0x108a28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3f1591600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108806d30>, '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 0x10872ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fbbe8>, '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.aAriOKVMGE/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.ioE07iaeRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 11:08:02 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 11:08:02 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 11:08:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAriOKVMGE/Render', 11:08:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:02 INFO - 'HOME': '/Users/cltbld', 11:08:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:02 INFO - 'LOGNAME': 'cltbld', 11:08:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:02 INFO - 'MOZ_NO_REMOTE': '1', 11:08:02 INFO - 'NO_EM_RESTART': '1', 11:08:02 INFO - 'PAGER': '/bin/cat', 11:08:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:02 INFO - 'PWD': '/builds/slave/test', 11:08:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:02 INFO - 'SHELL': '/bin/bash', 11:08:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ioE07iaeRg/Listeners', 11:08:02 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 11:08:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:02 INFO - 'USER': 'cltbld', 11:08:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:02 INFO - 'XPC_FLAGS': '0x0', 11:08:02 INFO - 'XPC_SERVICE_NAME': '0', 11:08:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:02 INFO - Downloading/unpacking jsonschema==2.5.1 11:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:08:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:05 INFO - Downloading functools32-3.2.3-2.tar.gz 11:08:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:08:05 INFO - warning: no files found matching '*.txt' 11:08:05 INFO - no previously-included directories found matching 'build' 11:08:05 INFO - no previously-included directories found matching 'dist' 11:08:05 INFO - no previously-included directories found matching '.git*' 11:08:05 INFO - Installing collected packages: jsonschema, functools32 11:08:05 INFO - Running setup.py install for functools32 11:08:05 INFO - warning: no files found matching '*.txt' 11:08:05 INFO - no previously-included directories found matching 'build' 11:08:05 INFO - no previously-included directories found matching 'dist' 11:08:05 INFO - no previously-included directories found matching '.git*' 11:08:05 INFO - Successfully installed jsonschema functools32 11:08:05 INFO - Cleaning up... 11:08:05 INFO - Return code: 0 11:08:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3f1591600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108806d30>, '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 0x10872ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fbbe8>, '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.aAriOKVMGE/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.ioE07iaeRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 11:08:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:08:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:08:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAriOKVMGE/Render', 11:08:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:05 INFO - 'HOME': '/Users/cltbld', 11:08:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:05 INFO - 'LOGNAME': 'cltbld', 11:08:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:05 INFO - 'MOZ_NO_REMOTE': '1', 11:08:05 INFO - 'NO_EM_RESTART': '1', 11:08:05 INFO - 'PAGER': '/bin/cat', 11:08:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:05 INFO - 'PWD': '/builds/slave/test', 11:08:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:05 INFO - 'SHELL': '/bin/bash', 11:08:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ioE07iaeRg/Listeners', 11:08:05 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 11:08:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:05 INFO - 'USER': 'cltbld', 11:08:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:05 INFO - 'XPC_FLAGS': '0x0', 11:08:05 INFO - 'XPC_SERVICE_NAME': '0', 11:08:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:08:06 INFO - Cleaning up... 11:08:06 INFO - Return code: 0 11:08:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:08:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:06 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 0x108a28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3f1591600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108806d30>, '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 0x10872ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fbbe8>, '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.aAriOKVMGE/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.ioE07iaeRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 11:08:06 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 11:08:06 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 11:08:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAriOKVMGE/Render', 11:08:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:06 INFO - 'HOME': '/Users/cltbld', 11:08:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:06 INFO - 'LOGNAME': 'cltbld', 11:08:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:06 INFO - 'MOZ_NO_REMOTE': '1', 11:08:06 INFO - 'NO_EM_RESTART': '1', 11:08:06 INFO - 'PAGER': '/bin/cat', 11:08:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:06 INFO - 'PWD': '/builds/slave/test', 11:08:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:06 INFO - 'SHELL': '/bin/bash', 11:08:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ioE07iaeRg/Listeners', 11:08:06 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 11:08:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:06 INFO - 'USER': 'cltbld', 11:08:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:06 INFO - 'XPC_FLAGS': '0x0', 11:08:06 INFO - 'XPC_SERVICE_NAME': '0', 11:08:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:06 INFO - Downloading/unpacking blobuploader==1.2.4 11:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:08 INFO - Downloading blobuploader-1.2.4.tar.gz 11:08:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:08:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:08:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:10 INFO - Downloading docopt-0.6.1.tar.gz 11:08:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:08:10 INFO - Installing collected packages: blobuploader, requests, docopt 11:08:10 INFO - Running setup.py install for blobuploader 11:08:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:08:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:08:10 INFO - Running setup.py install for requests 11:08:10 INFO - Running setup.py install for docopt 11:08:11 INFO - Successfully installed blobuploader requests docopt 11:08:11 INFO - Cleaning up... 11:08:11 INFO - Return code: 0 11:08:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:08:11 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')]} 11:08:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:11 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')]} 11:08:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:11 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 0x108a28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3f1591600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108806d30>, '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 0x10872ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fbbe8>, '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.aAriOKVMGE/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.ioE07iaeRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 11:08:11 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 11:08:11 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 11:08:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAriOKVMGE/Render', 11:08:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:11 INFO - 'HOME': '/Users/cltbld', 11:08:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:11 INFO - 'LOGNAME': 'cltbld', 11:08:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:11 INFO - 'MOZ_NO_REMOTE': '1', 11:08:11 INFO - 'NO_EM_RESTART': '1', 11:08:11 INFO - 'PAGER': '/bin/cat', 11:08:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:11 INFO - 'PWD': '/builds/slave/test', 11:08:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:11 INFO - 'SHELL': '/bin/bash', 11:08:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ioE07iaeRg/Listeners', 11:08:11 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 11:08:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:11 INFO - 'USER': 'cltbld', 11:08:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:11 INFO - 'XPC_FLAGS': '0x0', 11:08:11 INFO - 'XPC_SERVICE_NAME': '0', 11:08:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:08:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-1RdJtR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:08:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-NNyYTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:08:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-3CwJ9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:08:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-hN7H7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:08:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:08:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-fBUng7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:08:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-wQc3UQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:08:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-lslPsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:08:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-xpaDlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:08:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-7feOGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:08:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-uUyJFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:08:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-nWyItU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:08:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-V2Rd4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:08:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-3CA2Sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:08:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-GV8rYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-4lsMb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:08:13 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-mmJlnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:08:13 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-YM23n3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:08:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:08:13 INFO - Running setup.py install for manifestparser 11:08:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:08:13 INFO - Running setup.py install for mozcrash 11:08:13 INFO - Running setup.py install for mozdebug 11:08:13 INFO - Running setup.py install for mozdevice 11:08:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:08:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:08:13 INFO - Running setup.py install for mozfile 11:08:14 INFO - Running setup.py install for mozhttpd 11:08:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:08:14 INFO - Running setup.py install for mozinfo 11:08:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:08:14 INFO - Running setup.py install for mozInstall 11:08:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:08:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:08:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:08:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:08:14 INFO - Running setup.py install for mozleak 11:08:14 INFO - Running setup.py install for mozlog 11:08:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:08:14 INFO - Running setup.py install for moznetwork 11:08:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:08:14 INFO - Running setup.py install for mozprocess 11:08:15 INFO - Running setup.py install for mozprofile 11:08:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:08:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:08:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:08:15 INFO - Running setup.py install for mozrunner 11:08:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:08:15 INFO - Running setup.py install for mozscreenshot 11:08:15 INFO - Running setup.py install for moztest 11:08:15 INFO - Running setup.py install for mozversion 11:08:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:08:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:08:15 INFO - Cleaning up... 11:08:15 INFO - Return code: 0 11:08:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3f1591600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108806d30>, '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 0x10872ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fbbe8>, '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.aAriOKVMGE/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.ioE07iaeRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 11:08:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:08:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:08:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAriOKVMGE/Render', 11:08:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:15 INFO - 'HOME': '/Users/cltbld', 11:08:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:15 INFO - 'LOGNAME': 'cltbld', 11:08:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:15 INFO - 'MOZ_NO_REMOTE': '1', 11:08:15 INFO - 'NO_EM_RESTART': '1', 11:08:15 INFO - 'PAGER': '/bin/cat', 11:08:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:15 INFO - 'PWD': '/builds/slave/test', 11:08:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:15 INFO - 'SHELL': '/bin/bash', 11:08:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ioE07iaeRg/Listeners', 11:08:15 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 11:08:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:15 INFO - 'USER': 'cltbld', 11:08:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:15 INFO - 'XPC_FLAGS': '0x0', 11:08:15 INFO - 'XPC_SERVICE_NAME': '0', 11:08:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:08:16 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-3tj2Tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:08:16 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)) 11:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:08:16 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-eXfDog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:08:16 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)) 11:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:08:16 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-brcqH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:08:16 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)) 11:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:08:16 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-UA_7Wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:08:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:08:16 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-VmKBQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:08:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:08:16 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-g1dUim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:08:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:08:16 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-h63ano-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:08:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:08:16 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-H5bD3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:08:17 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)) 11:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:08:17 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-_fbZ11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:08:17 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)) 11:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:08:17 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-nSoalZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:08:17 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)) 11:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:08:17 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-YS9izt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:08:17 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)) 11:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:08:17 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-C9t7b6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:08:17 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-ZtkVdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:08:17 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-WiHtaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:08:17 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)) 11:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:17 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-JKfyK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:08:17 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-vSJAgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:08:17 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-eJowHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:08:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:08:18 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 11:08:18 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 11:08:18 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 11:08:18 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 11:08:18 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 11:08:18 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 11:08:20 INFO - Downloading blessings-1.6.tar.gz 11:08:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:08:20 INFO - Installing collected packages: blessings 11:08:20 INFO - Running setup.py install for blessings 11:08:20 INFO - Successfully installed blessings 11:08:20 INFO - Cleaning up... 11:08:21 INFO - Return code: 0 11:08:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:08:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:08:21 INFO - Reading from file tmpfile_stdout 11:08:21 INFO - Current package versions: 11:08:21 INFO - blessings == 1.6 11:08:21 INFO - blobuploader == 1.2.4 11:08:21 INFO - docopt == 0.6.1 11:08:21 INFO - functools32 == 3.2.3-2 11:08:21 INFO - jsonschema == 2.5.1 11:08:21 INFO - manifestparser == 1.1 11:08:21 INFO - mozInstall == 1.12 11:08:21 INFO - mozcrash == 1.0 11:08:21 INFO - mozdebug == 0.1 11:08:21 INFO - mozdevice == 0.49 11:08:21 INFO - mozfile == 1.2 11:08:21 INFO - mozhttpd == 0.7 11:08:21 INFO - mozinfo == 0.9 11:08:21 INFO - mozleak == 0.1 11:08:21 INFO - mozlog == 3.4 11:08:21 INFO - moznetwork == 0.27 11:08:21 INFO - mozprocess == 0.25 11:08:21 INFO - mozprofile == 0.28 11:08:21 INFO - mozrunner == 6.13 11:08:21 INFO - mozscreenshot == 0.1 11:08:21 INFO - mozsystemmonitor == 0.3 11:08:21 INFO - moztest == 0.8 11:08:21 INFO - mozversion == 1.4 11:08:21 INFO - psutil == 3.1.1 11:08:21 INFO - requests == 1.2.3 11:08:21 INFO - wsgiref == 0.1.2 11:08:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:21 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 0x108a28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3f1591600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108806d30>, '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 0x10872ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fbbe8>, '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.aAriOKVMGE/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.ioE07iaeRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 11:08:21 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 11:08:21 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 11:08:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAriOKVMGE/Render', 11:08:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:21 INFO - 'HOME': '/Users/cltbld', 11:08:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:21 INFO - 'LOGNAME': 'cltbld', 11:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:21 INFO - 'MOZ_NO_REMOTE': '1', 11:08:21 INFO - 'NO_EM_RESTART': '1', 11:08:21 INFO - 'PAGER': '/bin/cat', 11:08:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:21 INFO - 'PWD': '/builds/slave/test', 11:08:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:21 INFO - 'SHELL': '/bin/bash', 11:08:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ioE07iaeRg/Listeners', 11:08:21 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 11:08:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:21 INFO - 'USER': 'cltbld', 11:08:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:21 INFO - 'XPC_FLAGS': '0x0', 11:08:21 INFO - 'XPC_SERVICE_NAME': '0', 11:08:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:21 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)) 11:08:21 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)) 11:08:21 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)) 11:08:21 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)) 11:08:21 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)) 11:08:21 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)) 11:08:21 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)) 11:08:21 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)) 11:08:21 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)) 11:08:21 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)) 11:08:21 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)) 11:08:21 INFO - Cleaning up... 11:08:21 INFO - Return code: 0 11:08:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a28b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a2b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3f1591600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108806d30>, '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 0x10872ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fbbe8>, '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.aAriOKVMGE/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.ioE07iaeRg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 11:08:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:08:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAriOKVMGE/Render', 11:08:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:21 INFO - 'HOME': '/Users/cltbld', 11:08:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:21 INFO - 'LOGNAME': 'cltbld', 11:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:21 INFO - 'MOZ_NO_REMOTE': '1', 11:08:21 INFO - 'NO_EM_RESTART': '1', 11:08:21 INFO - 'PAGER': '/bin/cat', 11:08:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:21 INFO - 'PWD': '/builds/slave/test', 11:08:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:21 INFO - 'SHELL': '/bin/bash', 11:08:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ioE07iaeRg/Listeners', 11:08:21 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 11:08:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:21 INFO - 'USER': 'cltbld', 11:08:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:21 INFO - 'XPC_FLAGS': '0x0', 11:08:21 INFO - 'XPC_SERVICE_NAME': '0', 11:08:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:08:21 INFO - Cleaning up... 11:08:21 INFO - Return code: 0 11:08:21 INFO - Running post-action listener: _resource_record_post_action 11:08:21 INFO - Running post-action listener: _start_resource_monitoring 11:08:21 INFO - Starting resource monitoring. 11:08:21 INFO - [mozharness: 2017-03-17 18:08:21.885405Z] Finished create-virtualenv step (success) 11:08:21 INFO - [mozharness: 2017-03-17 18:08:21.886374Z] Running install step. 11:08:21 INFO - Running pre-action listener: _resource_record_pre_action 11:08:21 INFO - Running main action method: install 11:08:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:08:22 INFO - Reading from file tmpfile_stdout 11:08:22 INFO - Detecting whether we're running mozinstall >=1.0... 11:08:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:08:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:08:22 INFO - Reading from file tmpfile_stdout 11:08:22 INFO - Output received: 11:08:22 INFO - Usage: mozinstall [options] installer 11:08:22 INFO - Options: 11:08:22 INFO - -h, --help show this help message and exit 11:08:22 INFO - -d DEST, --destination=DEST 11:08:22 INFO - Directory to install application into. [default: 11:08:22 INFO - "/builds/slave/test"] 11:08:22 INFO - --app=APP Application being installed. [default: firefox] 11:08:22 INFO - mkdir: /builds/slave/test/build/application 11:08:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:08:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:08:43 INFO - Reading from file tmpfile_stdout 11:08:43 INFO - Output received: 11:08:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:08:43 INFO - Running post-action listener: _resource_record_post_action 11:08:43 INFO - [mozharness: 2017-03-17 18:08:43.418349Z] Finished install step (success) 11:08:43 INFO - [mozharness: 2017-03-17 18:08:43.418487Z] Running run-tests step. 11:08:43 INFO - Running pre-action listener: _resource_record_pre_action 11:08:43 INFO - Running main action method: run_tests 11:08:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:08:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:08:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:08:43 INFO - 2017-03-17 11:08:43.433 screenresolution[1633:7938] starting screenresolution argv=screenresolution get 11:08:43 INFO - 2017-03-17 11:08:43.436 screenresolution[1633:7938] Display 0: 1600x1200x32@60 11:08:43 INFO - 2017-03-17 11:08:43.442 screenresolution[1634:7941] starting screenresolution argv=screenresolution list 11:08:43 INFO - Available Modes on Display 0 11:08:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:08:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:08:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:08:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:08:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:08:43 INFO - Intel Iris: 11:08:43 INFO - Chipset Model: Intel Iris 11:08:43 INFO - Type: GPU 11:08:43 INFO - Bus: Built-In 11:08:43 INFO - VRAM (Dynamic, Max): 1536 MB 11:08:43 INFO - Vendor: Intel (0x8086) 11:08:43 INFO - Device ID: 0x0a2e 11:08:43 INFO - Revision ID: 0x0009 11:08:43 INFO - Displays: 11:08:43 INFO - Display: 11:08:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:08:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:08:43 INFO - Display Serial Number: 1600x1200 60 11:08:43 INFO - Main Display: Yes 11:08:43 INFO - Mirror: Off 11:08:43 INFO - Online: Yes 11:08:43 INFO - Rotation: Supported 11:08:43 INFO - Return code: 0 11:08:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:08:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:08:43 INFO - Python 2.7.3 11:08:43 INFO - Return code: 0 11:08:43 INFO - grabbing minidump binary from tooltool 11:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:43 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 0x108806d30>, '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 0x10872ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087fbbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:08:43 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 11:08:43 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 11:08:43 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 11:08:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:08:43 INFO - Return code: 0 11:08:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:08:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:08:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:08:43 INFO - ENV: RUST_BACKTRACE is now 1 11:08:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:08:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:08:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--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 11:08:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0246 --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 11:08:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAriOKVMGE/Render', 11:08:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:43 INFO - 'HOME': '/Users/cltbld', 11:08:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:43 INFO - 'LOGNAME': 'cltbld', 11:08:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:08:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:08:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:43 INFO - 'MOZ_NO_REMOTE': '1', 11:08:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:08:43 INFO - 'NO_EM_RESTART': '1', 11:08:43 INFO - 'PAGER': '/bin/cat', 11:08:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:43 INFO - 'PWD': '/builds/slave/test', 11:08:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:08:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:43 INFO - 'RUST_BACKTRACE': '1', 11:08:43 INFO - 'SHELL': '/bin/bash', 11:08:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ioE07iaeRg/Listeners', 11:08:43 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 11:08:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:43 INFO - 'USER': 'cltbld', 11:08:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:43 INFO - 'XPC_FLAGS': '0x0', 11:08:43 INFO - 'XPC_SERVICE_NAME': '0', 11:08:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--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 11:08:44 INFO - mozversion application_buildid: 20170317090120 11:08:44 INFO - mozversion application_changeset: bc5fff53646952aad7049f86e0340f919a8b5719 11:08:44 INFO - mozversion application_display_name: Nightly 11:08:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:08:44 INFO - mozversion application_name: Firefox 11:08:44 INFO - mozversion application_remotingname: firefox 11:08:44 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 11:08:44 INFO - mozversion application_vendor: Mozilla 11:08:44 INFO - mozversion application_version: 55.0a1 11:08:44 INFO - mozversion platform_buildid: 20170317090120 11:08:44 INFO - mozversion platform_changeset: bc5fff53646952aad7049f86e0340f919a8b5719 11:08:44 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 11:08:44 INFO - mozversion platform_version: 55.0a1 11:08:44 INFO - using testdate: 1489774124 11:08:44 INFO - actual date: 1489774124 11:08:44 INFO - starting webserver on 'localhost:49249' 11:08:44 INFO - SUITE-START | Running 2 tests 11:08:44 INFO - TEST-START | dromaeo_css 11:08:44 INFO - operating with platform_type : mac_ 11:08:44 INFO - Initialising browser for dromaeo_css test... 11:08:44 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmp8393ka/profile http://localhost:49249/getInfo.html) 11:08:47 INFO - PID 1644 | 2017-03-17 11:08:47.077 plugin-container[1646:8123] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 11:08:47 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:08:47 INFO - PID 1644 | 2017-03-17 11:08:47.079 plugin-container[1646:8123] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 11:08:47 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:08:47 INFO - PID 1644 | 2017-03-17 11:08:47.079 plugin-container[1646:8123] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:08:47 INFO - PID 1644 | __metrics Screen width/height:1600/1200 11:08:47 INFO - PID 1644 | colorDepth:24 11:08:47 INFO - PID 1644 | Browser inner width/height: 1024/689 11:08:47 INFO - PID 1644 | __metrics 11:08:47 ERROR - PID 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 11:08:48 INFO - TEST-INFO | 1644: exit 0 11:08:48 INFO - Browser initialized. 11:08:48 INFO - Running cycle 1/1 for dromaeo_css test... 11:08:48 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmp8393ka/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:08:50 INFO - PID 1647 | 2017-03-17 11:08:50.913 plugin-container[1648:8246] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 11:08:50 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:08:50 INFO - PID 1647 | 2017-03-17 11:08:50.914 plugin-container[1648:8246] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 11:08:50 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:08:50 INFO - PID 1647 | 2017-03-17 11:08:50.914 plugin-container[1648:8246] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:09:00 INFO - PID 1647 | RSS: Main: 227323904 11:09:00 INFO - PID 1647 | 11:12:03 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 11:12:05 INFO - PID 1647 | RSS: Main: 250908672 11:12:05 INFO - PID 1647 | 11:13:48 INFO - PID 1647 | ************************* 11:13:48 INFO - PID 1647 | A coding exception was thrown and uncaught in a Task. 11:13:48 INFO - PID 1647 | 11:13:48 ERROR - PID 1647 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:13:48 INFO - PID 1647 | Full stack: 11:13:48 INFO - PID 1647 | ************************* 11:15:07 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 11:15:08 INFO - PID 1647 | RSS: Main: 259686400 11:15:08 INFO - PID 1647 | 11:18:10 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 11:18:11 INFO - PID 1647 | RSS: Main: 271650816 11:18:11 INFO - PID 1647 | 11:21:13 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 11:21:13 INFO - PID 1647 | RSS: Main: 264491008 11:21:13 INFO - PID 1647 | 11:24:16 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 11:24:17 INFO - PID 1647 | RSS: Main: 274354176 11:24:17 INFO - PID 1647 | 11:27:20 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 11:27:20 INFO - PID 1647 | RSS: Main: 268836864 11:27:20 INFO - PID 1647 | 11:27:20 INFO - PID 1647 | __start_tp_report 11:27:20 INFO - PID 1647 | _x_x_mozilla_page_load 11:27:20 INFO - PID 1647 | _x_x_mozilla_page_load_details 11:27:20 INFO - PID 1647 | |i|pagename|runs| 11:27:20 INFO - PID 1647 | |0;dojo.html;3389.6610338966098;3410.1304167437283;3440.415129427997;3454.9481757773638;3457.25668981169;4361.825526978925;5407.891842163154;5834.824955251344;5852.67810270436;5892.941070589292;5722.427757224275;5738.196652468648;5890.58765886387;5917.911231331534;5948.256467941507;4920.261960705905;5169.6898186108765;5257.684538927658;5312.521873031436;5375;5364.195370694388;5425.7287135643255;5437.157240627689;5444.863878403052;5462.20797984292;4254.404383386328;5187.922181167267;5265.6840589564745;5319.787208511655;5328.600354973373;5201.817936372209;5282.603804714662;5321.8935621287355;5325.961437519685;5360.3567571891235;5210.6613070150315;5257.737113144366;5273.709945952971;5274.340707411574;5293.602979776494;5581.274434323512;5651.632643878134;5677.630953987978;5807.883842323172;5827.912581311284;6211.378862113797;6257.8748425031245;6259.217597800275;6266.749330026793;6283.968580157116;6233.3143354231;6240;6244.750209991595;6248.5313601480075;6251;5697.829065128053;5741.282339707536;5992.310884248323;5998.400159983967;6027.819165425044;6210.9068363974575;6236.469900058546;6268.529860260499;6304.306526282106;6307.905381419282;6105.236845394325;6121.8493044937295;6131.774283661951;6148.8770224595255;6190.380961903864;6170.074488826712;6224.408681175281;6226.128342032119;6244.344343843922;6258.15514905491;6251.249850018027;6252.3122456529745;6265.843353916098;6272.717727702265;6281.371862813682;6130.734471166763;6190.969045154746;6220.968895155495;6227.626342419469;6227.657478838707;5014.623903207274;5037.823676171316;5043.949560504348;5060.139776237975;5073.21365188389;4479.283314669638;4480.887977800581;4498.347739577743;4510.909781804411;4523.977380113144;5241.135212689864;5401.8116014476755;5776.393478684761;5887.528997680175;5907.438793314628;6162.414570615784;6216.098665693449;6219.595726277777;6242.719077641517;6247.937520624827;5891.086881533369;5896.7641294348605;5904.645721256738;5916.526677865761;5919.763209471659;4840.273958906122;5162.483751624807;5527.419620939824;5553.77784888608;5560.527355174774;6147.815565533041;6250.593711408653;6305.337939516285;6342.048692696132;6358.713857876499;6220.440160385587;6266.498680105672;6269.811905642838;6284.214473190851;6297.181366422519;6351.555391122577;6386.105945167773;6395.680215989275;6425.839354016187;6433;4778.832740854054;6127.953177891068;6154.569180157346;6166.81499555014;6231.439170474498;5099.592032637306;5325.121354976385;6211.720472578654;6214.005759078525;6219.813405597839;6040.124181993674;6091;6200.844978875564;6207.937920620736;6210.223722034746;6094.299155597163;6121.051237058263;6133.120447061136;6228.626282423069;6241.21984751906;5911.261092363455;6075.544334175043;6122.825106970096;6156.29202641702;6159.199304090619;6115.544587951936;6131.601445906002;6132.662703551169;6136.325004249618;6163.630182189083;6075.726592303446;6110.938890611037;6111.786087486917;6112.633242005494;6143.84640383994 11:27:20 INFO - PID 1647 | |1;ext.html;660.2539130782216;711.3206887422511;798.8801679748037;923.7829110159107;929.893062297836;37419.25161496768;38393.84818455444;38473.61526384735;38516;38518;37406.625933740725;37780.81109594455;38873.80563097181;38888.41667374992;39130.21739565207;7712.113106992698;7854.646540905673;10622.521986510626;10862.511186996604;10882.891171088306;10846.240763146583;10973.670789876276;10984.450777461136;11004.944975275152;11048.447577621127;10912.399818010006;10914.126869850394;10996.670099896975;11003.119750419988;11012.339259644446;10945.960133787277;10995.835062474069;11015.614453494089;11016.394098324588;11099.889001109967;10788;10789.406582637952;10842.945785271102;10881.23831331807;10892.509837057272;38732;38941;38979.220415591815;38984.80507597444;38995.805020975;3853.224647555803;3869.090763670535;3880.9029774255587;3885.0870045539277;4104.117614712851;21859.23397649587;27602;27758.722412775816;28164.85917570399;29525.704742952727;3110.620375412811;3124.6406663233565;3128.2012842615586;3129.85915633797;3181.3955348483714;320745;323403;324521;325125;325790;51979.74010129925;51995;52005.47994520082;52011;52048.479515204366;53772.462275377526;53902.460975389746;54061.45938540643;54220;54262.72868635631;14532.273386330853;14815.851841481663;14850.851491484947;14856.331465084097;14877.107373557621;10269.897301027044;11036.337819730841;11862.525498979945;12004.399780010965;12007.579734709243;13855.16868987864;14215.289235538181;14244;14322.57032289033;14398.42406303736;4845.709257444564;5522.6134170608475;5668.801591944262;5999.400059994053;6104.26748790148;901.378049146087;995.8945570416831;1067.1089640150406;1080.6920027792041;1121.6635009497118;19011.429657110308;25956.351091222492;26125.869370653025;26319.868400657873;26450.6032409514;1130.3161587239601;1140.492480846032;1166.6077219269457;1182.5801840346726;1190.1133655426763;13398.732025359634;13420.597382078553;13556.864431355758;13560.932195339157;13639.795403068962;424368;427860;427873;428419;428795.7120428818;52911.73544132255;56331.87336253252;56901.430985690444;56991.430085699445;57475.71262143662;1379.5309594737837;1410.6332353588346;1419.5386499387741;1421.822272215973;1425.7291114688383;26844.06045788388;28524.714752853037;29304.413911722073;29397.349836547957;29587.85206073956;44480.55519444764;44539.554604453544;44655;44815.327770082826;44943;353788.2310588431;355156;355556;355890;357125;32414.027579172664;32455.675443245265;33035.834820826705;33083.17292067621;33186.17034574155;40768.776936691946;41437;42144.78927605342;42259.154816904105;42490.36264455974;29450.705492945654;34572;34633;35606.821965890005;35779;34134.65865341414;34252;34892.65107348996;35254;35316.117097072776;34648;34693.47959780555;35413.82293088518;35547.28905421929;35623;25305.493890121725;28546.714532854407;29514.409711805216;29811.403771924874;31427.685723142476;18806.811931880507;19248.903755481693;20750.792492074885;23587.764122358556;23859 11:27:20 INFO - PID 1647 | |2;jquery.html;380.28506407953046;395.78429755783094;417.107390185004;421.35322280299744;426.1200620718217;20873.68689469659;21321.36035918921;21360.786392136095;21520.677189842165;22693.432664183405;19670.803291967077;22260;22604.643721376728;22787;23348.832558372054;6391.328910464404;6722.462203023746;6728.629925354104;6734.158230221223;6734.595924244537;5193.688378697271;7583;7652.349550288204;7658.081030276375;7685.462017658766;7326.963365183166;7548.584827834495;7614.619269036553;7696.807579810494;7712.807179820521;6665.800025999203;7640.662883995278;7670.194629563899;7695.115061767857;7698.884516732225;7203.991441198236;7432.368248698813;7470.701171953116;7484.663190156456;7490.101187857437;25121.497570048497;26520.734792652212;26688.332791680168;27187.728122718716;27243.91024359023;3325.6508066652896;3331.3004269103517;3338.5159151923076;3349.3133907548895;3477.6522347765276;13890.58328250154;16481.093539855305;16573.585660358465;17013.404530841362;17138.40015599448;1741.651669666072;1807.4216250799618;2000.619882222373;2003.5992801439625;2036.4806974221626;126890.36554817167;144508.27745861202;152636;152899;153125;21371.763917840228;22164;22391.888040560018;22508.98709558074;22852;36297.45553816695;36547.086322842144;36777.08057298589;36803.079923001605;36904;8732.563371831384;9325.766855828522;10346;10503.94748026255;10969.177311701655;8453.830923381622;8459.830803383898;8497.532635704973;8616.621340585476;8622.655093796178;10594.894051059391;10889.455527223607;10917.126629869592;10944.507497162647;11022.50398732059;3855.63371479709;4484.775761211927;4490.146872094291;4503.639708823286;4512.75179865104;1052.1372474570778;1054.8923630188272;1063.4363787192801;1069.2942657845786;1114.4260705736551;22882.65676014861;22987.96554155067;23028.424289392566;23097.422564436023;23401.882990584938;951.8239125761756;1017.0744622393586;1068.9150512230092;1095.956161753521;1099.4173088263233;10618.946905265579;12061.698457538632;12674.302913339854;13320.351507889032;13426.530071447647;643327;652138;654015;656495;662092;73856.63071684608;114656.34888499013;125125;145504;153295.46704533036;1231.846019247594;1298.8311519502404;1321.2732996851694;1327.143992125074;1418.1065928465;15737.527874163794;21196.576068478236;21209.681854772498;21834.672479912497;21989.340319790434;62560.687196563726;64044.35955640384;64380.67809660922;64472;64727;88249.6762548575;88782;89200.5539972296;89585.55207223922;90107;9947.204223662235;11887.643370698894;11929.701757456132;12069.27584344942;12323.568675096329;13604.795928060888;13624.65938351549;13763.518276860263;13995.370208340853;14041.736243738113;9526.85709714341;11388.487518061542;11553;11680.707982300171;11733.706657333634;11471;11612.88387116118;11858.525658973544;11871.703207419883;11948.522059117886;10207.591696332063;11646.301221926711;12136.453859576164;12153.088518361335;12223.93888030554;11926.642200733992;12198.14612977083;12211.816822747844;12246.12216129191;12287.447064881922;10183.79632407333;11582.189246752647;11700.829917008232;11920;11970.281783093042 11:27:20 INFO - PID 1647 | |3;mootools.html;1419.5031738891396;1445.7686770116777;1459.6350912271932;1478.2165452310276;1495.5961890289623;10533;10897.019268265854;11025.724356891082;11036.11711063115;11246.212765106446;9322;9489.240860731143;10847;11236.719082022953;11254.155938304619;2135.562209746998;2184.6395344768093;2185.726784151981;2185.9781402185936;2192.725909261342;2338.2166974063707;2376;2388.0686532252435;2404.362843846382;2405.6391541268863;1958.4516335426104;1975.6246313200518;2319.8492098013576;2331.9650205246935;2336.5677349690277;2291.2438895164555;2302.343832007878;2317.6060069788177;2319.1766922742436;2328.476092879097;2298.390926404504;2366.9467087146227;2375.857448553092;2376.453415714378;2380.880955952213;10984.121270298358;11005.889941100566;11075.50160242791;11102.61140860074;11110.333379997146;5616.466435688603;5830.533557315404;5834.299884013945;5845.5908086433965;5851.297844258674;3346.4311067118406;3436.862525498977;3547.330623840924;3711.3319602471543;3712.533549248059;1108.1965574958172;1153.8211577205548;1236.4003458322707;1245.5765039886392;1253.7617852607975;13841;13993.580192594238;14812.407503699838;15192.696146077016;15247.923760381238;4515.14212299662;4579.335996280521;4729.408823897013;4737.692050016807;4777.044591081728;11422;11450.828237576443;11482.598109066143;11504.194706370474;11513.366764828015;5643.858903527444;5644;5694.259746232963;5722.599418040697;5757.165211044375;5088.9745551272745;5220.817271395483;5254.97372513135;5271.947280527223;5303.549198318186;3799.2401519696173;3847.2690188864585;3890.280298144796;3912.139329347596;3935.2129574085297;1021.7394564386183;1022.1771473963382;1024.5850430575629;1037.0355569320454;1040.7554224757175;798.7563793043178;835.9205875441824;907.3920473282916;915.6108653822099;943.4386540008821;6378.904316435257;6432.06735023419;6584.835379115465;6627.37039981201;6771.424428923498;1004.5083051668266;1019.3934608907688;1029.6756521695643;1031.515187861719;1056.27645063132;3248.691374319436;4152.418661387348;4162.854300099482;4181.770002649883;4403.053343531092;12961.481540738265;13228.735425291441;13279.601611951657;13311.267880266678;13468.595942121752;13223.404946777418;13455.86544134546;13515;13537.796933046013;13578.93210533941;8405.159484051668;9997.700068997941;10117.140043096271;10190.898091018995;10201.74495637615;5834.329052158886;6303.306636270098;6516.218053833571;6792.72829086831;6821.113255276782;9738.318317717974;11486.770264594828;11898.107641927063;11932.403379831147;12032.398380081137;11988.64034078979;11989.76020479603;12167.2699638022;12212.938935305267;12307.876921230672;8997.235235004837;9279.814403711753;9311.487868167316;9311.86032209531;9312.161905428544;10096.84854727165;10100.040496152853;10159.593616255266;10205.489725513546;10239.078482936571;8342.332613391036;8365.539895305817;8967.506787126771;9278;9300.674476393564;8798.12018798115;8859.3798434109;8879.378443508893;8944.016158222721;8985.955070224607;7695.57674327895;7822.100458447351;7943;7953.443258971816;7957.403194760301;5482.643628164156;5747.511461525817;5889;5920.200772895604;5944.1083837423;5760.510356619734;5864.589478736448;5913.91129133072;5928.8814223716145;5943.078822782476 11:27:20 INFO - PID 1647 | |4;prototype.html;369.6691461142278;370.77753347991194;384.83644451108273;387.04014045168;394.34144977886933;5803.361630220672;5888.528917686585;5929.792457263997;5999.790007349744;6043.274807023158;5735.942640573593;5807.767689292434;5881.558883083764;5901.380355062721;5906.881862362761;5140.640155189139;5189.471159611463;5198.749263244132;5201.55786758126;5203.089459344599;4765.189917713979;4969.304297398349;5150.407703114133;5182.144946083891;5194.194899790538;4180;5082.085224659579;5082.466341034194;5115.335006449156;5159.974200128994;5102.2091575805625;5136.640435169558;5139;5151.712071982004;5158.303629010074;4566.40636717228;4817.831375901862;4844.563989240985;4856.644527404206;4889.486603906575;4605.976970115128;5243.711595862226;5795.275590551181;5825;5853.85365365865;4575.58819706228;5979.910301345483;5995.880082398371;6006.669633170174;6009.489193418564;4759;5766.971165144148;6256.749730010794;6317.4946004319545;6432.391902024494;5450.91823622646;5454.890902181974;5455.1544510600925;5455.727213639302;5474.233607294982;6428.582142160792;6608.735650573972;6635.668216589152;6694.966525167343;6696.263411024832;4325.653947684178;5537.640053396516;5611.551075913917;5619.915701264485;5648.943510564924;5478.890422191614;5779.739911703983;5780.716616936371;5784.161296612052;5792.855178620568;4987.875303117451;5736.483716465538;5933.071751680721;5983.521318294526;6037.516998640098;6418.2080794853455;6429.517786165963;6443.742250310029;6450.774222902176;6452.257990331172;1870.7568016157813;1985.97430620766;1993.4717299915535;1996.1715887906357;2017.9607502136412;1364.6724786051475;1364.822573065515;1492.731308364505;1589.8870790446733;1627.1050921992858;5142.791444010654;5505.807296744595;5818.5636077294375;5916.319623243296;5972.372900845436;5442.782288708487;5744.310682718101;6047.246298573365;6229.408206220402;6545.967270163714;4882.755862206946;5938.970305148533;5949.240607513972;5949.494292985059;5963.880722385528;3038.9696103038686;3257.9348413031607;3376.74674399421;3400.6089299730847;3451.430513965218;5922.970385148046;5944.910826337608;6383.968080159662;6415.877221486221;6607.80176594703;5555.944440555623;6481.689676365164;6504.284528701839;6701.497387695845;6749.490265062238;5688.890666320028;6476.352364763486;6530.771423000172;6694.564853284521;6737.427318677967;5888.970555147368;6050.319488817856;6503.869922601616;6513.8697226056165;6543.378379053982;6063.6664983426335;6353.841153971003;6393.528574638715;6428.582142160744;6454.741810327722;6166.163564760261;6749.493787965824;6753.86492270142;6770.01834733951;6777.45780337562;5325.893482130258;5516.779328826802;5602.991461536963;5605.747741351567;5640.802571909963;5448.83653490396;6467.773627923;6494.805155845333;6508.9349106508325;6590.340965903371;5321.594179725056;5491.807786727445;5625.887482250414;6236.251649801871;6247.687615619292;5249.632525723163;6253.812385628438;6262.2172228471445;6271.341509141657;6283.842903927439;5713.628614140068;6090.421409966223;6182.196314479089;6187.194086504809;6203.4416902479;5540.40146678248;6287.654179020014;6312.011716309348;6343.0485427186295;6353.5870168438905;5422.593305502182;6191.752329906753;6198.419403052193;6206.317305096345;6216.627002379691 11:27:20 INFO - PID 1647 | |5;yui.html;709.411188713368;739.6190961654748;740.1820987808475;767.888656144859;803.9959800201;15109.395624175033;15829.762553561706;16254.268557914893;16440.588985275343;16447.671046579075;14694.338754756034;14980.400783968655;15164.317605707742;15260.38958441661;15366.539003829876;938.8591711243317;958.7219706285169;961.2790407194605;962.1196605106319;962.4562122400856;952.8761261036055;973.4500007495742;987.7652933832709;1000.8048430556045;1001.8998100189959;1039.4231201683062;1046.5865982936773;1048.1352883870836;1049.7113293844177;1050.3487837540697;1031.4739482863718;1036.2953191829554;1039.537405854399;1039.9220058495569;1041.8228901086834;767.3017554025865;768.2586304216458;774.6746366526072;775.1589525364985;777.3625626985873;16216.918915405466;16529.421470248602;16609;16912.323507059704;17151.65696686059;14633.853661463356;14710.632234194123;14789.112653240732;14863.479778207819;15530.301136448774;11328.207025508218;11345.546178152865;11679.883201167964;11695.298282103016;11783.351915644636;3699.5560532735976;3719.4048952167796;3752.549694036733;3758.8872333830027;3910.120222949842;86161;106859;112990;113677.43161284224;113978.04307397053;8943.971443284106;9799.657012004545;11434.485448154854;11478;11938.433236854118;21037.158513659284;23267.767322326898;23286.883565582404;23294;23492.7650723494;6852.349026842442;7993.400494962901;8036.8748375227515;8076.8384632307025;8119.715809946623;4144.689148313889;4211.1998720243055;4223.535411104776;4253.340732186516;4255.297875850512;3051.298201413643;3541.9291614167573;3579.2304654499408;3587;3610.981945090258;513.1814755465027;608.027156549526;661.0645936000506;667.9699413526403;676.5703778100941;629.7040391016216;649.3603800256743;667.6394746836654;686.8591938652564;707.3633729643259;9642.75893102664;10138.898611013943;10183.512788017277;10198.745031374276;10479.790404191874;621.1645337021698;674.1236392689484;679.9800299550674;682.9005301464638;714.3820595185127;4292;4602.194615942197;4910.410750709938;4911.631627627942;4915.188320818512;97634;98804;99198.50400748095;101237;102545;82146.17853821504;83456.58271708603;83698.16301837025;84491;86756.26487470216;56361.71819140878;64682.67658661676;73866.5226695463;75021.6248918752;75260.24739752532;9423.858642120236;9554.952225239107;9577.616895324387;9594.136527712539;9609.231261499202;35011.47482787709;48399;48571.75714121548;49393.50606493889;50413.99172016466;63849;64999.67500162658;65435.69128617306;65822.34177658163;66380.66809665921;11674.474648640662;11685.941570292434;11716;11854.644360669194;12291.69270768238;12327;12703.682407939876;12703.96736522152;12906.612801615967;13164.539241126515;10722;11260.549578016788;11880;12318.75362492763;13019;13610.659733506345;13806.861931380558;14125.717485650024;14149.858501415265;14218.360173792411;12556.748865022832;12794.872051279368;12806;12818.935905320413;13694.589162325146;11131.888681113409;11136.888631113585;11258.943705281421;11274.830877536679;11466.770664586495;7254.077484693751;10246.282760207012;11404.714882128013;11483.712907177387;11633.709157270798 11:27:20 INFO - PID 1647 | __end_tp_report 11:27:20 INFO - PID 1647 | __start_cc_report 11:27:20 INFO - PID 1647 | _x_x_mozilla_cycle_collect,429 11:27:20 INFO - PID 1647 | __end_cc_report 11:27:20 INFO - PID 1647 | __startTimestamp1489775240382__endTimestamp 11:27:20 INFO - PID 1647 | 11:27:20 INFO - PID 1647 | ------- Summary: start ------- 11:27:20 INFO - PID 1647 | Number of tests: 6 11:27:20 INFO - PID 1647 | 11:27:20 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:5761.89 Median:6091.00 stddev:651.61 (10.7%) stddev-sans-first:626.48 11:27:20 INFO - PID 1647 | Values: 3389.7 3410.1 3440.4 3454.9 3457.3 4361.8 5407.9 5834.8 5852.7 5892.9 5722.4 5738.2 5890.6 5917.9 5948.3 4920.3 5169.7 5257.7 5312.5 5375.0 5364.2 5425.7 5437.2 5444.9 5462.2 4254.4 5187.9 5265.7 5319.8 5328.6 5201.8 5282.6 5321.9 5326.0 5360.4 5210.7 5257.7 5273.7 5274.3 5293.6 5581.3 5651.6 5677.6 5807.9 5827.9 6211.4 6257.9 6259.2 6266.7 6284.0 6233.3 6240.0 6244.8 6248.5 6251.0 5697.8 5741.3 5992.3 5998.4 6027.8 6210.9 6236.5 6268.5 6304.3 6307.9 6105.2 6121.8 6131.8 6148.9 6190.4 6170.1 6224.4 6226.1 6244.3 6258.2 6251.2 6252.3 6265.8 6272.7 6281.4 6130.7 6191.0 6221.0 6227.6 6227.7 5014.6 5037.8 5043.9 5060.1 5073.2 4479.3 4480.9 4498.3 4510.9 4524.0 5241.1 5401.8 5776.4 5887.5 5907.4 6162.4 6216.1 6219.6 6242.7 6247.9 5891.1 5896.8 5904.6 5916.5 5919.8 4840.3 5162.5 5527.4 5553.8 5560.5 6147.8 6250.6 6305.3 6342.0 6358.7 6220.4 6266.5 6269.8 6284.2 6297.2 6351.6 6386.1 6395.7 6425.8 6433.0 4778.8 6128.0 6154.6 6166.8 6231.4 5099.6 5325.1 6211.7 6214.0 6219.8 6040.1 6091.0 6200.8 6207.9 6210.2 6094.3 6121.1 6133.1 6228.6 6241.2 5911.3 6075.5 6122.8 6156.3 6159.2 6115.5 6131.6 6132.7 6136.3 6163.6 6075.7 6110.9 6111.8 6112.6 6143.8 11:27:20 INFO - PID 1647 | 11:27:20 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:51751.26 Median:178488.34 stddev:98045.04 (54.9%) stddev-sans-first:98245.04 11:27:20 INFO - PID 1647 | Values: 660.3 711.3 798.9 923.8 929.9 37419.3 38393.8 38473.6 38516.0 38518.0 37406.6 37780.8 38873.8 38888.4 39130.2 7712.1 7854.6 10622.5 10862.5 10882.9 10846.2 10973.7 10984.5 11004.9 11048.4 10912.4 10914.1 10996.7 11003.1 11012.3 10946.0 10995.8 11015.6 11016.4 11099.9 10788.0 10789.4 10842.9 10881.2 10892.5 38732.0 38941.0 38979.2 38984.8 38995.8 3853.2 3869.1 3880.9 3885.1 4104.1 21859.2 27602.0 27758.7 28164.9 29525.7 3110.6 3124.6 3128.2 3129.9 3181.4 320745.0 323403.0 324521.0 325125.0 325790.0 51979.7 51995.0 52005.5 52011.0 52048.5 53772.5 53902.5 54061.5 54220.0 54262.7 14532.3 14815.9 14850.9 14856.3 14877.1 10269.9 11036.3 11862.5 12004.4 12007.6 13855.2 14215.3 14244.0 14322.6 14398.4 4845.7 5522.6 5668.8 5999.4 6104.3 901.4 995.9 1067.1 1080.7 1121.7 19011.4 25956.4 26125.9 26319.9 26450.6 1130.3 1140.5 1166.6 1182.6 1190.1 13398.7 13420.6 13556.9 13560.9 13639.8 424368.0 427860.0 427873.0 428419.0 428795.7 52911.7 56331.9 56901.4 56991.4 57475.7 1379.5 1410.6 1419.5 1421.8 1425.7 26844.1 28524.7 29304.4 29397.3 29587.9 44480.6 44539.6 44655.0 44815.3 44943.0 353788.2 355156.0 355556.0 355890.0 357125.0 32414.0 32455.7 33035.8 33083.2 33186.2 40768.8 41437.0 42144.8 42259.2 42490.4 29450.7 34572.0 34633.0 35606.8 35779.0 34134.7 34252.0 34892.7 35254.0 35316.1 34648.0 34693.5 35413.8 35547.3 35623.0 25305.5 28546.7 29514.4 29811.4 31427.7 18806.8 19248.9 20750.8 23587.8 23859.0 11:27:20 INFO - PID 1647 | 11:27:20 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:39985.02 Median:22212.00 stddev:109011.85 (490.8%) stddev-sans-first:109277.09 11:27:20 INFO - PID 1647 | Values: 380.3 395.8 417.1 421.4 426.1 20873.7 21321.4 21360.8 21520.7 22693.4 19670.8 22260.0 22604.6 22787.0 23348.8 6391.3 6722.5 6728.6 6734.2 6734.6 5193.7 7583.0 7652.3 7658.1 7685.5 7327.0 7548.6 7614.6 7696.8 7712.8 6665.8 7640.7 7670.2 7695.1 7698.9 7204.0 7432.4 7470.7 7484.7 7490.1 25121.5 26520.7 26688.3 27187.7 27243.9 3325.7 3331.3 3338.5 3349.3 3477.7 13890.6 16481.1 16573.6 17013.4 17138.4 1741.7 1807.4 2000.6 2003.6 2036.5 126890.4 144508.3 152636.0 152899.0 153125.0 21371.8 22164.0 22391.9 22509.0 22852.0 36297.5 36547.1 36777.1 36803.1 36904.0 8732.6 9325.8 10346.0 10503.9 10969.2 8453.8 8459.8 8497.5 8616.6 8622.7 10594.9 10889.5 10917.1 10944.5 11022.5 3855.6 4484.8 4490.1 4503.6 4512.8 1052.1 1054.9 1063.4 1069.3 1114.4 22882.7 22988.0 23028.4 23097.4 23401.9 951.8 1017.1 1068.9 1096.0 1099.4 10618.9 12061.7 12674.3 13320.4 13426.5 643327.0 652138.0 654015.0 656495.0 662092.0 73856.6 114656.3 125125.0 145504.0 153295.5 1231.8 1298.8 1321.3 1327.1 1418.1 15737.5 21196.6 21209.7 21834.7 21989.3 62560.7 64044.4 64380.7 64472.0 64727.0 88249.7 88782.0 89200.6 89585.6 90107.0 9947.2 11887.6 11929.7 12069.3 12323.6 13604.8 13624.7 13763.5 13995.4 14041.7 9526.9 11388.5 11553.0 11680.7 11733.7 11471.0 11612.9 11858.5 11871.7 11948.5 10207.6 11646.3 12136.5 12153.1 12223.9 11926.6 12198.1 12211.8 12246.1 12287.4 10183.8 11582.2 11700.8 11920.0 11970.3 11:27:20 INFO - PID 1647 | 11:27:20 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:6614.92 Median:2378.67 stddev:4116.25 (173.0%) stddev-sans-first:4109.29 11:27:20 INFO - PID 1647 | Values: 1419.5 1445.8 1459.6 1478.2 1495.6 10533.0 10897.0 11025.7 11036.1 11246.2 9322.0 9489.2 10847.0 11236.7 11254.2 2135.6 2184.6 2185.7 2186.0 2192.7 2338.2 2376.0 2388.1 2404.4 2405.6 1958.5 1975.6 2319.8 2332.0 2336.6 2291.2 2302.3 2317.6 2319.2 2328.5 2298.4 2366.9 2375.9 2376.5 2380.9 10984.1 11005.9 11075.5 11102.6 11110.3 5616.5 5830.5 5834.3 5845.6 5851.3 3346.4 3436.9 3547.3 3711.3 3712.5 1108.2 1153.8 1236.4 1245.6 1253.8 13841.0 13993.6 14812.4 15192.7 15247.9 4515.1 4579.3 4729.4 4737.7 4777.0 11422.0 11450.8 11482.6 11504.2 11513.4 5643.9 5644.0 5694.3 5722.6 5757.2 5089.0 5220.8 5255.0 5271.9 5303.5 3799.2 3847.3 3890.3 3912.1 3935.2 1021.7 1022.2 1024.6 1037.0 1040.8 798.8 835.9 907.4 915.6 943.4 6378.9 6432.1 6584.8 6627.4 6771.4 1004.5 1019.4 1029.7 1031.5 1056.3 3248.7 4152.4 4162.9 4181.8 4403.1 12961.5 13228.7 13279.6 13311.3 13468.6 13223.4 13455.9 13515.0 13537.8 13578.9 8405.2 9997.7 10117.1 10190.9 10201.7 5834.3 6303.3 6516.2 6792.7 6821.1 9738.3 11486.8 11898.1 11932.4 12032.4 11988.6 11989.8 12167.3 12212.9 12307.9 8997.2 9279.8 9311.5 9311.9 9312.2 10096.8 10100.0 10159.6 10205.5 10239.1 8342.3 8365.5 8967.5 9278.0 9300.7 8798.1 8859.4 8879.4 8944.0 8986.0 7695.6 7822.1 7943.0 7953.4 7957.4 5482.6 5747.5 5889.0 5920.2 5944.1 5760.5 5864.6 5913.9 5928.9 5943.1 11:27:20 INFO - PID 1647 | 11:27:20 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5393.77 Median:5813.17 stddev:1436.32 (24.7%) stddev-sans-first:1389.96 11:27:20 INFO - PID 1647 | Values: 369.7 370.8 384.8 387.0 394.3 5803.4 5888.5 5929.8 5999.8 6043.3 5735.9 5807.8 5881.6 5901.4 5906.9 5140.6 5189.5 5198.7 5201.6 5203.1 4765.2 4969.3 5150.4 5182.1 5194.2 4180.0 5082.1 5082.5 5115.3 5160.0 5102.2 5136.6 5139.0 5151.7 5158.3 4566.4 4817.8 4844.6 4856.6 4889.5 4606.0 5243.7 5795.3 5825.0 5853.9 4575.6 5979.9 5995.9 6006.7 6009.5 4759.0 5767.0 6256.7 6317.5 6432.4 5450.9 5454.9 5455.2 5455.7 5474.2 6428.6 6608.7 6635.7 6695.0 6696.3 4325.7 5537.6 5611.6 5619.9 5648.9 5478.9 5779.7 5780.7 5784.2 5792.9 4987.9 5736.5 5933.1 5983.5 6037.5 6418.2 6429.5 6443.7 6450.8 6452.3 1870.8 1986.0 1993.5 1996.2 2018.0 1364.7 1364.8 1492.7 1589.9 1627.1 5142.8 5505.8 5818.6 5916.3 5972.4 5442.8 5744.3 6047.2 6229.4 6546.0 4882.8 5939.0 5949.2 5949.5 5963.9 3039.0 3257.9 3376.7 3400.6 3451.4 5923.0 5944.9 6384.0 6415.9 6607.8 5555.9 6481.7 6504.3 6701.5 6749.5 5688.9 6476.4 6530.8 6694.6 6737.4 5889.0 6050.3 6503.9 6513.9 6543.4 6063.7 6353.8 6393.5 6428.6 6454.7 6166.2 6749.5 6753.9 6770.0 6777.5 5325.9 5516.8 5603.0 5605.7 5640.8 5448.8 6467.8 6494.8 6508.9 6590.3 5321.6 5491.8 5625.9 6236.3 6247.7 5249.6 6253.8 6262.2 6271.3 6283.8 5713.6 6090.4 6182.2 6187.2 6203.4 5540.4 6287.7 6312.0 6343.0 6353.6 5422.6 6191.8 6198.4 6206.3 6216.6 11:27:20 INFO - PID 1647 | 11:27:20 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:19875.03 Median:19276.70 stddev:28334.20 (147.0%) stddev-sans-first:28377.14 11:27:20 INFO - PID 1647 | Values: 709.4 739.6 740.2 767.9 804.0 15109.4 15829.8 16254.3 16440.6 16447.7 14694.3 14980.4 15164.3 15260.4 15366.5 938.9 958.7 961.3 962.1 962.5 952.9 973.5 987.8 1000.8 1001.9 1039.4 1046.6 1048.1 1049.7 1050.3 1031.5 1036.3 1039.5 1039.9 1041.8 767.3 768.3 774.7 775.2 777.4 16216.9 16529.4 16609.0 16912.3 17151.7 14633.9 14710.6 14789.1 14863.5 15530.3 11328.2 11345.5 11679.9 11695.3 11783.4 3699.6 3719.4 3752.5 3758.9 3910.1 86161.0 106859.0 112990.0 113677.4 113978.0 8944.0 9799.7 11434.5 11478.0 11938.4 21037.2 23267.8 23286.9 23294.0 23492.8 6852.3 7993.4 8036.9 8076.8 8119.7 4144.7 4211.2 4223.5 4253.3 4255.3 3051.3 3541.9 3579.2 3587.0 3611.0 513.2 608.0 661.1 668.0 676.6 629.7 649.4 667.6 686.9 707.4 9642.8 10138.9 10183.5 10198.7 10479.8 621.2 674.1 680.0 682.9 714.4 4292.0 4602.2 4910.4 4911.6 4915.2 97634.0 98804.0 99198.5 101237.0 102545.0 82146.2 83456.6 83698.2 84491.0 86756.3 56361.7 64682.7 73866.5 75021.6 75260.2 9423.9 9555.0 9577.6 9594.1 9609.2 35011.5 48399.0 48571.8 49393.5 50414.0 63849.0 64999.7 65435.7 65822.3 66380.7 11674.5 11685.9 11716.0 11854.6 12291.7 12327.0 12703.7 12704.0 12906.6 13164.5 10722.0 11260.5 11880.0 12318.8 13019.0 13610.7 13806.9 14125.7 14149.9 14218.4 12556.7 12794.9 12806.0 12818.9 13694.6 11131.9 11136.9 11258.9 11274.8 11466.8 7254.1 10246.3 11404.7 11483.7 11633.7 11:27:20 INFO - PID 1647 | -------- Summary: end -------- 11:27:20 INFO - PID 1647 | 11:27:21 INFO - TEST-INFO | 1647: exit 0 11:27:21 INFO - TEST-OK | dromaeo_css | took 1117049ms 11:27:21 INFO - TEST-START | kraken 11:27:21 INFO - operating with platform_type : mac_ 11:27:21 INFO - Initialising browser for kraken test... 11:27:21 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpnzG4u4/profile http://localhost:49249/getInfo.html) 11:27:24 INFO - PID 1655 | 2017-03-17 11:27:24.166 plugin-container[1656:10501] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 11:27:24 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:27:24 INFO - PID 1655 | 2017-03-17 11:27:24.169 plugin-container[1656:10501] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 11:27:24 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:27:24 INFO - PID 1655 | 2017-03-17 11:27:24.169 plugin-container[1656:10501] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:27:24 INFO - PID 1655 | __metrics Screen width/height:1600/1200 11:27:24 INFO - PID 1655 | colorDepth:24 11:27:24 INFO - PID 1655 | Browser inner width/height: 1024/689 11:27:24 INFO - PID 1655 | __metrics 11:27:24 ERROR - PID 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 11:27:25 INFO - TEST-INFO | 1655: exit 0 11:27:25 INFO - Browser initialized. 11:27:25 INFO - Running cycle 1/1 for kraken test... 11:27:25 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpnzG4u4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:27:27 INFO - PID 1657 | 2017-03-17 11:27:27.521 plugin-container[1658:10626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4f, name = 'com.apple.tsm.portname' 11:27:27 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:27:27 INFO - PID 1657 | 2017-03-17 11:27:27.522 plugin-container[1658:10626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b13, name = 'com.apple.CFPasteboardClient' 11:27:27 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:27:27 INFO - PID 1657 | 2017-03-17 11:27:27.522 plugin-container[1658:10626] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:27:37 INFO - PID 1657 | RSS: Main: 227921920 11:27:37 INFO - PID 1657 | 11:27:40 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 11:27:41 INFO - PID 1657 | RSS: Main: 241950720 11:27:41 INFO - PID 1657 | 11:27:43 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 11:27:44 INFO - PID 1657 | RSS: Main: 250462208 11:27:44 INFO - PID 1657 | 11:27:47 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 11:27:48 INFO - PID 1657 | RSS: Main: 252915712 11:27:48 INFO - PID 1657 | 11:27:50 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 11:27:50 INFO - PID 1657 | RSS: Main: 254287872 11:27:50 INFO - PID 1657 | 11:27:52 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:27:53 INFO - PID 1657 | RSS: Main: 254685184 11:27:53 INFO - PID 1657 | 11:27:56 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 11:27:56 INFO - PID 1657 | RSS: Main: 259969024 11:27:56 INFO - PID 1657 | 11:28:00 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 11:28:00 INFO - PID 1657 | RSS: Main: 261701632 11:28:00 INFO - PID 1657 | 11:28:03 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 11:28:04 INFO - PID 1657 | RSS: Main: 260763648 11:28:04 INFO - PID 1657 | 11:28:06 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:28:06 INFO - PID 1657 | RSS: Main: 260083712 11:28:06 INFO - PID 1657 | 11:28:08 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:28:09 INFO - PID 1657 | RSS: Main: 261414912 11:28:09 INFO - PID 1657 | 11:28:11 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:28:12 INFO - PID 1657 | RSS: Main: 261550080 11:28:12 INFO - PID 1657 | 11:28:15 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:28:16 INFO - PID 1657 | RSS: Main: 261844992 11:28:16 INFO - PID 1657 | 11:28:19 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:28:19 INFO - PID 1657 | RSS: Main: 261873664 11:28:19 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 11:28:21 INFO - PID 1657 | RSS: Main: 263462912 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | __start_tp_report 11:28:21 INFO - PID 1657 | _x_x_mozilla_page_load 11:28:21 INFO - PID 1657 | _x_x_mozilla_page_load_details 11:28:21 INFO - PID 1657 | |i|pagename|runs| 11:28:21 INFO - PID 1657 | |0;ai-astar;107;135;178;156;117;119;119;121;117;119 11:28:21 INFO - PID 1657 | |1;audio-beat-detection;148;115;116;153;115;118;115;115;165;148 11:28:21 INFO - PID 1657 | |2;audio-dft;192;200;203;209;203;221;261;196;196;190 11:28:21 INFO - PID 1657 | |3;audio-fft;75;58;62;86;58;60;60;63;75;60 11:28:21 INFO - PID 1657 | |4;audio-oscillator;90;72;85;71;70;70;83;71;72;82 11:28:21 INFO - PID 1657 | |5;imaging-gaussian-blur;90;91;92;91;83;137;110;89;90;92 11:28:21 INFO - PID 1657 | |6;imaging-darkroom;96;97;102;156;111;96;98;105;94;132 11:28:21 INFO - PID 1657 | |7;imaging-desaturate;76;77;145;144;106;90;92;110;124;87 11:28:21 INFO - PID 1657 | |8;json-parse-financial;67;68;67;68;69;67;70;68;67;68 11:28:21 INFO - PID 1657 | |9;json-stringify-tinderbox;56;63;62;75;82;91;84;53;56;58 11:28:21 INFO - PID 1657 | |10;stanford-crypto-aes;99;102;112;83;85;85;83;105;104;107 11:28:21 INFO - PID 1657 | |11;stanford-crypto-ccm;139;139;139;141;136;154;156;152;204;150 11:28:21 INFO - PID 1657 | |12;stanford-crypto-pbkdf2;188;201;185;187;185;188;188;203;235;196 11:28:21 INFO - PID 1657 | |13;stanford-crypto-sha256-iterative;69;67;71;68;77;67;71;69;66;67 11:28:21 INFO - PID 1657 | __end_tp_report 11:28:21 INFO - PID 1657 | __start_cc_report 11:28:21 INFO - PID 1657 | _x_x_mozilla_cycle_collect,999 11:28:21 INFO - PID 1657 | __end_cc_report 11:28:21 INFO - PID 1657 | __startTimestamp1489775301742__endTimestamp 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | ------- Summary: start ------- 11:28:21 INFO - PID 1657 | Number of tests: 14 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | [#0] ai-astar Cycles:10 Average:128.80 Median:120.00 stddev:21.86 (18.2%) stddev-sans-first:21.72 11:28:21 INFO - PID 1657 | Values: 107.0 135.0 178.0 156.0 117.0 119.0 119.0 121.0 117.0 119.0 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | [#1] audio-beat-detection Cycles:10 Average:130.80 Median:133.00 stddev:20.10 (15.1%) stddev-sans-first:20.33 11:28:21 INFO - PID 1657 | Values: 148.0 115.0 116.0 153.0 115.0 118.0 115.0 115.0 165.0 148.0 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | [#2] audio-dft Cycles:10 Average:207.10 Median:203.00 stddev:20.96 (10.3%) stddev-sans-first:21.50 11:28:21 INFO - PID 1657 | Values: 192.0 200.0 203.0 209.0 203.0 221.0 261.0 196.0 196.0 190.0 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | [#3] audio-fft Cycles:10 Average:65.70 Median:62.50 stddev:9.56 (15.3%) stddev-sans-first:9.53 11:28:21 INFO - PID 1657 | Values: 75.0 58.0 62.0 86.0 58.0 60.0 60.0 63.0 75.0 60.0 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | [#4] audio-oscillator Cycles:10 Average:76.60 Median:77.00 stddev:7.55 (9.8%) stddev-sans-first:6.25 11:28:21 INFO - PID 1657 | Values: 90.0 72.0 85.0 71.0 70.0 70.0 83.0 71.0 72.0 82.0 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:96.50 Median:90.50 stddev:15.80 (17.5%) stddev-sans-first:16.58 11:28:21 INFO - PID 1657 | Values: 90.0 91.0 92.0 91.0 83.0 137.0 110.0 89.0 90.0 92.0 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | [#6] imaging-darkroom Cycles:10 Average:108.70 Median:95.00 stddev:20.09 (21.2%) stddev-sans-first:20.78 11:28:21 INFO - PID 1657 | Values: 96.0 97.0 102.0 156.0 111.0 96.0 98.0 105.0 94.0 132.0 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | [#7] imaging-desaturate Cycles:10 Average:105.10 Median:76.50 stddev:25.50 (33.3%) stddev-sans-first:24.77 11:28:21 INFO - PID 1657 | Values: 76.0 77.0 145.0 144.0 106.0 90.0 92.0 110.0 124.0 87.0 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | [#8] json-parse-financial Cycles:10 Average:67.90 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:1.00 11:28:21 INFO - PID 1657 | Values: 67.0 68.0 67.0 68.0 69.0 67.0 70.0 68.0 67.0 68.0 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:69.00 stddev:13.76 (19.9%) stddev-sans-first:13.89 11:28:21 INFO - PID 1657 | Values: 56.0 63.0 62.0 75.0 82.0 91.0 84.0 53.0 56.0 58.0 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | [#10] stanford-crypto-aes Cycles:10 Average:96.50 Median:83.00 stddev:11.28 (13.6%) stddev-sans-first:11.92 11:28:21 INFO - PID 1657 | Values: 99.0 102.0 112.0 83.0 85.0 85.0 83.0 105.0 104.0 107.0 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:151.00 Median:151.00 stddev:20.01 (13.2%) stddev-sans-first:20.74 11:28:21 INFO - PID 1657 | Values: 139.0 139.0 139.0 141.0 136.0 154.0 156.0 152.0 204.0 150.0 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.60 Median:192.00 stddev:15.31 (8.0%) stddev-sans-first:15.99 11:28:21 INFO - PID 1657 | Values: 188.0 201.0 185.0 187.0 185.0 188.0 188.0 203.0 235.0 196.0 11:28:21 INFO - PID 1657 | 11:28:21 INFO - PID 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.00 stddev:3.22 (4.7%) stddev-sans-first:3.42 11:28:21 INFO - PID 1657 | Values: 69.0 67.0 71.0 68.0 77.0 67.0 71.0 69.0 66.0 67.0 11:28:21 INFO - PID 1657 | -------- Summary: end -------- 11:28:21 INFO - PID 1657 | 11:28:22 INFO - TEST-INFO | 1657: exit 0 11:28:22 INFO - TEST-OK | kraken | took 61546ms 11:28:22 INFO - SUITE-END | took 1178s 11:28:22 INFO - Completed test suite (00:19:38) 11:28:22 INFO - javascript benchmark 11:28:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7895.288682650004, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3389.6610338966098, 3410.1304167437283, 3440.415129427997, 3454.9481757773638, 3457.25668981169, 4361.825526978925, 5407.891842163154, 5834.824955251344, 5852.67810270436, 5892.941070589292, 5722.427757224275, 5738.196652468648, 5890.58765886387, 5917.911231331534, 5948.256467941507, 4920.261960705905, 5169.6898186108765, 5257.684538927658, 5312.521873031436, 5375.0, 5364.195370694388, 5425.7287135643255, 5437.157240627689, 5444.863878403052, 5462.20797984292, 4254.404383386328, 5187.922181167267, 5265.6840589564745, 5319.787208511655, 5328.600354973373, 5201.817936372209, 5282.603804714662, 5321.8935621287355, 5325.961437519685, 5360.3567571891235, 5210.6613070150315, 5257.737113144366, 5273.709945952971, 5274.340707411574, 5293.602979776494, 5581.274434323512, 5651.632643878134, 5677.630953987978, 5807.883842323172, 5827.912581311284, 6211.378862113797, 6257.8748425031245, 6259.217597800275, 6266.749330026793, 6283.968580157116, 6233.3143354231, 6240.0, 6244.750209991595, 6248.5313601480075, 6251.0, 5697.829065128053, 5741.282339707536, 5992.310884248323, 5998.400159983967, 6027.819165425044, 6210.9068363974575, 6236.469900058546, 6268.529860260499, 6304.306526282106, 6307.905381419282, 6105.236845394325, 6121.8493044937295, 6131.774283661951, 6148.8770224595255, 6190.380961903864, 6170.074488826712, 6224.408681175281, 6226.128342032119, 6244.344343843922, 6258.15514905491, 6251.249850018027, 6252.3122456529745, 6265.843353916098, 6272.717727702265, 6281.371862813682, 6130.734471166763, 6190.969045154746, 6220.968895155495, 6227.626342419469, 6227.657478838707, 5014.623903207274, 5037.823676171316, 5043.949560504348, 5060.139776237975, 5073.21365188389, 4479.283314669638, 4480.887977800581, 4498.347739577743, 4510.909781804411, 4523.977380113144, 5241.135212689864, 5401.8116014476755, 5776.393478684761, 5887.528997680175, 5907.438793314628, 6162.414570615784, 6216.098665693449, 6219.595726277777, 6242.719077641517, 6247.937520624827, 5891.086881533369, 5896.7641294348605, 5904.645721256738, 5916.526677865761, 5919.763209471659, 4840.273958906122, 5162.483751624807, 5527.419620939824, 5553.77784888608, 5560.527355174774, 6147.815565533041, 6250.593711408653, 6305.337939516285, 6342.048692696132, 6358.713857876499, 6220.440160385587, 6266.498680105672, 6269.811905642838, 6284.214473190851, 6297.181366422519, 6351.555391122577, 6386.105945167773, 6395.680215989275, 6425.839354016187, 6433.0, 4778.832740854054, 6127.953177891068, 6154.569180157346, 6166.81499555014, 6231.439170474498, 5099.592032637306, 5325.121354976385, 6211.720472578654, 6214.005759078525, 6219.813405597839, 6040.124181993674, 6091.0, 6200.844978875564, 6207.937920620736, 6210.223722034746, 6094.299155597163, 6121.051237058263, 6133.120447061136, 6228.626282423069, 6241.21984751906, 5911.261092363455, 6075.544334175043, 6122.825106970096, 6156.29202641702, 6159.199304090619, 6115.544587951936, 6131.601445906002, 6132.662703551169, 6136.325004249618, 6163.630182189083, 6075.726592303446, 6110.938890611037, 6111.786087486917, 6112.633242005494, 6143.84640383994], "value": 5722.60087119845, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.2539130782216, 711.3206887422511, 798.8801679748037, 923.7829110159107, 929.893062297836, 37419.25161496768, 38393.84818455444, 38473.61526384735, 38516.0, 38518.0, 37406.625933740725, 37780.81109594455, 38873.80563097181, 38888.41667374992, 39130.21739565207, 7712.113106992698, 7854.646540905673, 10622.521986510626, 10862.511186996604, 10882.891171088306, 10846.240763146583, 10973.670789876276, 10984.450777461136, 11004.944975275152, 11048.447577621127, 10912.399818010006, 10914.126869850394, 10996.670099896975, 11003.119750419988, 11012.339259644446, 10945.960133787277, 10995.835062474069, 11015.614453494089, 11016.394098324588, 11099.889001109967, 10788.0, 10789.406582637952, 10842.945785271102, 10881.23831331807, 10892.509837057272, 38732.0, 38941.0, 38979.220415591815, 38984.80507597444, 38995.805020975, 3853.224647555803, 3869.090763670535, 3880.9029774255587, 3885.0870045539277, 4104.117614712851, 21859.23397649587, 27602.0, 27758.722412775816, 28164.85917570399, 29525.704742952727, 3110.620375412811, 3124.6406663233565, 3128.2012842615586, 3129.85915633797, 3181.3955348483714, 320745.0, 323403.0, 324521.0, 325125.0, 325790.0, 51979.74010129925, 51995.0, 52005.47994520082, 52011.0, 52048.479515204366, 53772.462275377526, 53902.460975389746, 54061.45938540643, 54220.0, 54262.72868635631, 14532.273386330853, 14815.851841481663, 14850.851491484947, 14856.331465084097, 14877.107373557621, 10269.897301027044, 11036.337819730841, 11862.525498979945, 12004.399780010965, 12007.579734709243, 13855.16868987864, 14215.289235538181, 14244.0, 14322.57032289033, 14398.42406303736, 4845.709257444564, 5522.6134170608475, 5668.801591944262, 5999.400059994053, 6104.26748790148, 901.378049146087, 995.8945570416831, 1067.1089640150406, 1080.6920027792041, 1121.6635009497118, 19011.429657110308, 25956.351091222492, 26125.869370653025, 26319.868400657873, 26450.6032409514, 1130.3161587239601, 1140.492480846032, 1166.6077219269457, 1182.5801840346726, 1190.1133655426763, 13398.732025359634, 13420.597382078553, 13556.864431355758, 13560.932195339157, 13639.795403068962, 424368.0, 427860.0, 427873.0, 428419.0, 428795.7120428818, 52911.73544132255, 56331.87336253252, 56901.430985690444, 56991.430085699445, 57475.71262143662, 1379.5309594737837, 1410.6332353588346, 1419.5386499387741, 1421.822272215973, 1425.7291114688383, 26844.06045788388, 28524.714752853037, 29304.413911722073, 29397.349836547957, 29587.85206073956, 44480.55519444764, 44539.554604453544, 44655.0, 44815.327770082826, 44943.0, 353788.2310588431, 355156.0, 355556.0, 355890.0, 357125.0, 32414.027579172664, 32455.675443245265, 33035.834820826705, 33083.17292067621, 33186.17034574155, 40768.776936691946, 41437.0, 42144.78927605342, 42259.154816904105, 42490.36264455974, 29450.705492945654, 34572.0, 34633.0, 35606.821965890005, 35779.0, 34134.65865341414, 34252.0, 34892.65107348996, 35254.0, 35316.117097072776, 34648.0, 34693.47959780555, 35413.82293088518, 35547.28905421929, 35623.0, 25305.493890121725, 28546.714532854407, 29514.409711805216, 29811.403771924874, 31427.685723142476, 18806.811931880507, 19248.903755481693, 20750.792492074885, 23587.764122358556, 23859.0], "value": 18660.747955413608, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.28506407953046, 395.78429755783094, 417.107390185004, 421.35322280299744, 426.1200620718217, 20873.68689469659, 21321.36035918921, 21360.786392136095, 21520.677189842165, 22693.432664183405, 19670.803291967077, 22260.0, 22604.643721376728, 22787.0, 23348.832558372054, 6391.328910464404, 6722.462203023746, 6728.629925354104, 6734.158230221223, 6734.595924244537, 5193.688378697271, 7583.0, 7652.349550288204, 7658.081030276375, 7685.462017658766, 7326.963365183166, 7548.584827834495, 7614.619269036553, 7696.807579810494, 7712.807179820521, 6665.800025999203, 7640.662883995278, 7670.194629563899, 7695.115061767857, 7698.884516732225, 7203.991441198236, 7432.368248698813, 7470.701171953116, 7484.663190156456, 7490.101187857437, 25121.497570048497, 26520.734792652212, 26688.332791680168, 27187.728122718716, 27243.91024359023, 3325.6508066652896, 3331.3004269103517, 3338.5159151923076, 3349.3133907548895, 3477.6522347765276, 13890.58328250154, 16481.093539855305, 16573.585660358465, 17013.404530841362, 17138.40015599448, 1741.651669666072, 1807.4216250799618, 2000.619882222373, 2003.5992801439625, 2036.4806974221626, 126890.36554817167, 144508.27745861202, 152636.0, 152899.0, 153125.0, 21371.763917840228, 22164.0, 22391.888040560018, 22508.98709558074, 22852.0, 36297.45553816695, 36547.086322842144, 36777.08057298589, 36803.079923001605, 36904.0, 8732.563371831384, 9325.766855828522, 10346.0, 10503.94748026255, 10969.177311701655, 8453.830923381622, 8459.830803383898, 8497.532635704973, 8616.621340585476, 8622.655093796178, 10594.894051059391, 10889.455527223607, 10917.126629869592, 10944.507497162647, 11022.50398732059, 3855.63371479709, 4484.775761211927, 4490.146872094291, 4503.639708823286, 4512.75179865104, 1052.1372474570778, 1054.8923630188272, 1063.4363787192801, 1069.2942657845786, 1114.4260705736551, 22882.65676014861, 22987.96554155067, 23028.424289392566, 23097.422564436023, 23401.882990584938, 951.8239125761756, 1017.0744622393586, 1068.9150512230092, 1095.956161753521, 1099.4173088263233, 10618.946905265579, 12061.698457538632, 12674.302913339854, 13320.351507889032, 13426.530071447647, 643327.0, 652138.0, 654015.0, 656495.0, 662092.0, 73856.63071684608, 114656.34888499013, 125125.0, 145504.0, 153295.46704533036, 1231.846019247594, 1298.8311519502404, 1321.2732996851694, 1327.143992125074, 1418.1065928465, 15737.527874163794, 21196.576068478236, 21209.681854772498, 21834.672479912497, 21989.340319790434, 62560.687196563726, 64044.35955640384, 64380.67809660922, 64472.0, 64727.0, 88249.6762548575, 88782.0, 89200.5539972296, 89585.55207223922, 90107.0, 9947.204223662235, 11887.643370698894, 11929.701757456132, 12069.27584344942, 12323.568675096329, 13604.795928060888, 13624.65938351549, 13763.518276860263, 13995.370208340853, 14041.736243738113, 9526.85709714341, 11388.487518061542, 11553.0, 11680.707982300171, 11733.706657333634, 11471.0, 11612.88387116118, 11858.525658973544, 11871.703207419883, 11948.522059117886, 10207.591696332063, 11646.301221926711, 12136.453859576164, 12153.088518361335, 12223.93888030554, 11926.642200733992, 12198.14612977083, 12211.816822747844, 12246.12216129191, 12287.447064881922, 10183.79632407333, 11582.189246752647, 11700.829917008232, 11920.0, 11970.281783093042], "value": 12002.114247515248, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.5031738891396, 1445.7686770116777, 1459.6350912271932, 1478.2165452310276, 1495.5961890289623, 10533.0, 10897.019268265854, 11025.724356891082, 11036.11711063115, 11246.212765106446, 9322.0, 9489.240860731143, 10847.0, 11236.719082022953, 11254.155938304619, 2135.562209746998, 2184.6395344768093, 2185.726784151981, 2185.9781402185936, 2192.725909261342, 2338.2166974063707, 2376.0, 2388.0686532252435, 2404.362843846382, 2405.6391541268863, 1958.4516335426104, 1975.6246313200518, 2319.8492098013576, 2331.9650205246935, 2336.5677349690277, 2291.2438895164555, 2302.343832007878, 2317.6060069788177, 2319.1766922742436, 2328.476092879097, 2298.390926404504, 2366.9467087146227, 2375.857448553092, 2376.453415714378, 2380.880955952213, 10984.121270298358, 11005.889941100566, 11075.50160242791, 11102.61140860074, 11110.333379997146, 5616.466435688603, 5830.533557315404, 5834.299884013945, 5845.5908086433965, 5851.297844258674, 3346.4311067118406, 3436.862525498977, 3547.330623840924, 3711.3319602471543, 3712.533549248059, 1108.1965574958172, 1153.8211577205548, 1236.4003458322707, 1245.5765039886392, 1253.7617852607975, 13841.0, 13993.580192594238, 14812.407503699838, 15192.696146077016, 15247.923760381238, 4515.14212299662, 4579.335996280521, 4729.408823897013, 4737.692050016807, 4777.044591081728, 11422.0, 11450.828237576443, 11482.598109066143, 11504.194706370474, 11513.366764828015, 5643.858903527444, 5644.0, 5694.259746232963, 5722.599418040697, 5757.165211044375, 5088.9745551272745, 5220.817271395483, 5254.97372513135, 5271.947280527223, 5303.549198318186, 3799.2401519696173, 3847.2690188864585, 3890.280298144796, 3912.139329347596, 3935.2129574085297, 1021.7394564386183, 1022.1771473963382, 1024.5850430575629, 1037.0355569320454, 1040.7554224757175, 798.7563793043178, 835.9205875441824, 907.3920473282916, 915.6108653822099, 943.4386540008821, 6378.904316435257, 6432.06735023419, 6584.835379115465, 6627.37039981201, 6771.424428923498, 1004.5083051668266, 1019.3934608907688, 1029.6756521695643, 1031.515187861719, 1056.27645063132, 3248.691374319436, 4152.418661387348, 4162.854300099482, 4181.770002649883, 4403.053343531092, 12961.481540738265, 13228.735425291441, 13279.601611951657, 13311.267880266678, 13468.595942121752, 13223.404946777418, 13455.86544134546, 13515.0, 13537.796933046013, 13578.93210533941, 8405.159484051668, 9997.700068997941, 10117.140043096271, 10190.898091018995, 10201.74495637615, 5834.329052158886, 6303.306636270098, 6516.218053833571, 6792.72829086831, 6821.113255276782, 9738.318317717974, 11486.770264594828, 11898.107641927063, 11932.403379831147, 12032.398380081137, 11988.64034078979, 11989.76020479603, 12167.2699638022, 12212.938935305267, 12307.876921230672, 8997.235235004837, 9279.814403711753, 9311.487868167316, 9311.86032209531, 9312.161905428544, 10096.84854727165, 10100.040496152853, 10159.593616255266, 10205.489725513546, 10239.078482936571, 8342.332613391036, 8365.539895305817, 8967.506787126771, 9278.0, 9300.674476393564, 8798.12018798115, 8859.3798434109, 8879.378443508893, 8944.016158222721, 8985.955070224607, 7695.57674327895, 7822.100458447351, 7943.0, 7953.443258971816, 7957.403194760301, 5482.643628164156, 5747.511461525817, 5889.0, 5920.200772895604, 5944.1083837423, 5760.510356619734, 5864.589478736448, 5913.91129133072, 5928.8814223716145, 5943.078822782476], "value": 5040.265342762926, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.6691461142278, 370.77753347991194, 384.83644451108273, 387.04014045168, 394.34144977886933, 5803.361630220672, 5888.528917686585, 5929.792457263997, 5999.790007349744, 6043.274807023158, 5735.942640573593, 5807.767689292434, 5881.558883083764, 5901.380355062721, 5906.881862362761, 5140.640155189139, 5189.471159611463, 5198.749263244132, 5201.55786758126, 5203.089459344599, 4765.189917713979, 4969.304297398349, 5150.407703114133, 5182.144946083891, 5194.194899790538, 4180.0, 5082.085224659579, 5082.466341034194, 5115.335006449156, 5159.974200128994, 5102.2091575805625, 5136.640435169558, 5139.0, 5151.712071982004, 5158.303629010074, 4566.40636717228, 4817.831375901862, 4844.563989240985, 4856.644527404206, 4889.486603906575, 4605.976970115128, 5243.711595862226, 5795.275590551181, 5825.0, 5853.85365365865, 4575.58819706228, 5979.910301345483, 5995.880082398371, 6006.669633170174, 6009.489193418564, 4759.0, 5766.971165144148, 6256.749730010794, 6317.4946004319545, 6432.391902024494, 5450.91823622646, 5454.890902181974, 5455.1544510600925, 5455.727213639302, 5474.233607294982, 6428.582142160792, 6608.735650573972, 6635.668216589152, 6694.966525167343, 6696.263411024832, 4325.653947684178, 5537.640053396516, 5611.551075913917, 5619.915701264485, 5648.943510564924, 5478.890422191614, 5779.739911703983, 5780.716616936371, 5784.161296612052, 5792.855178620568, 4987.875303117451, 5736.483716465538, 5933.071751680721, 5983.521318294526, 6037.516998640098, 6418.2080794853455, 6429.517786165963, 6443.742250310029, 6450.774222902176, 6452.257990331172, 1870.7568016157813, 1985.97430620766, 1993.4717299915535, 1996.1715887906357, 2017.9607502136412, 1364.6724786051475, 1364.822573065515, 1492.731308364505, 1589.8870790446733, 1627.1050921992858, 5142.791444010654, 5505.807296744595, 5818.5636077294375, 5916.319623243296, 5972.372900845436, 5442.782288708487, 5744.310682718101, 6047.246298573365, 6229.408206220402, 6545.967270163714, 4882.755862206946, 5938.970305148533, 5949.240607513972, 5949.494292985059, 5963.880722385528, 3038.9696103038686, 3257.9348413031607, 3376.74674399421, 3400.6089299730847, 3451.430513965218, 5922.970385148046, 5944.910826337608, 6383.968080159662, 6415.877221486221, 6607.80176594703, 5555.944440555623, 6481.689676365164, 6504.284528701839, 6701.497387695845, 6749.490265062238, 5688.890666320028, 6476.352364763486, 6530.771423000172, 6694.564853284521, 6737.427318677967, 5888.970555147368, 6050.319488817856, 6503.869922601616, 6513.8697226056165, 6543.378379053982, 6063.6664983426335, 6353.841153971003, 6393.528574638715, 6428.582142160744, 6454.741810327722, 6166.163564760261, 6749.493787965824, 6753.86492270142, 6770.01834733951, 6777.45780337562, 5325.893482130258, 5516.779328826802, 5602.991461536963, 5605.747741351567, 5640.802571909963, 5448.83653490396, 6467.773627923, 6494.805155845333, 6508.9349106508325, 6590.340965903371, 5321.594179725056, 5491.807786727445, 5625.887482250414, 6236.251649801871, 6247.687615619292, 5249.632525723163, 6253.812385628438, 6262.2172228471445, 6271.341509141657, 6283.842903927439, 5713.628614140068, 6090.421409966223, 6182.196314479089, 6187.194086504809, 6203.4416902479, 5540.40146678248, 6287.654179020014, 6312.011716309348, 6343.0485427186295, 6353.5870168438905, 5422.593305502182, 6191.752329906753, 6198.419403052193, 6206.317305096345, 6216.627002379691], "value": 4965.890363645141, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.411188713368, 739.6190961654748, 740.1820987808475, 767.888656144859, 803.9959800201, 15109.395624175033, 15829.762553561706, 16254.268557914893, 16440.588985275343, 16447.671046579075, 14694.338754756034, 14980.400783968655, 15164.317605707742, 15260.38958441661, 15366.539003829876, 938.8591711243317, 958.7219706285169, 961.2790407194605, 962.1196605106319, 962.4562122400856, 952.8761261036055, 973.4500007495742, 987.7652933832709, 1000.8048430556045, 1001.8998100189959, 1039.4231201683062, 1046.5865982936773, 1048.1352883870836, 1049.7113293844177, 1050.3487837540697, 1031.4739482863718, 1036.2953191829554, 1039.537405854399, 1039.9220058495569, 1041.8228901086834, 767.3017554025865, 768.2586304216458, 774.6746366526072, 775.1589525364985, 777.3625626985873, 16216.918915405466, 16529.421470248602, 16609.0, 16912.323507059704, 17151.65696686059, 14633.853661463356, 14710.632234194123, 14789.112653240732, 14863.479778207819, 15530.301136448774, 11328.207025508218, 11345.546178152865, 11679.883201167964, 11695.298282103016, 11783.351915644636, 3699.5560532735976, 3719.4048952167796, 3752.549694036733, 3758.8872333830027, 3910.120222949842, 86161.0, 106859.0, 112990.0, 113677.43161284224, 113978.04307397053, 8943.971443284106, 9799.657012004545, 11434.485448154854, 11478.0, 11938.433236854118, 21037.158513659284, 23267.767322326898, 23286.883565582404, 23294.0, 23492.7650723494, 6852.349026842442, 7993.400494962901, 8036.8748375227515, 8076.8384632307025, 8119.715809946623, 4144.689148313889, 4211.1998720243055, 4223.535411104776, 4253.340732186516, 4255.297875850512, 3051.298201413643, 3541.9291614167573, 3579.2304654499408, 3587.0, 3610.981945090258, 513.1814755465027, 608.027156549526, 661.0645936000506, 667.9699413526403, 676.5703778100941, 629.7040391016216, 649.3603800256743, 667.6394746836654, 686.8591938652564, 707.3633729643259, 9642.75893102664, 10138.898611013943, 10183.512788017277, 10198.745031374276, 10479.790404191874, 621.1645337021698, 674.1236392689484, 679.9800299550674, 682.9005301464638, 714.3820595185127, 4292.0, 4602.194615942197, 4910.410750709938, 4911.631627627942, 4915.188320818512, 97634.0, 98804.0, 99198.50400748095, 101237.0, 102545.0, 82146.17853821504, 83456.58271708603, 83698.16301837025, 84491.0, 86756.26487470216, 56361.71819140878, 64682.67658661676, 73866.5226695463, 75021.6248918752, 75260.24739752532, 9423.858642120236, 9554.952225239107, 9577.616895324387, 9594.136527712539, 9609.231261499202, 35011.47482787709, 48399.0, 48571.75714121548, 49393.50606493889, 50413.99172016466, 63849.0, 64999.67500162658, 65435.69128617306, 65822.34177658163, 66380.66809665921, 11674.474648640662, 11685.941570292434, 11716.0, 11854.644360669194, 12291.69270768238, 12327.0, 12703.682407939876, 12703.96736522152, 12906.612801615967, 13164.539241126515, 10722.0, 11260.549578016788, 11880.0, 12318.75362492763, 13019.0, 13610.659733506345, 13806.861931380558, 14125.717485650024, 14149.858501415265, 14218.360173792411, 12556.748865022832, 12794.872051279368, 12806.0, 12818.935905320413, 13694.589162325146, 11131.888681113409, 11136.888631113585, 11258.943705281421, 11274.830877536679, 11466.770664586495, 7254.077484693751, 10246.282760207012, 11404.714882128013, 11483.712907177387, 11633.709157270798], "value": 7549.894000827287, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1567.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 135.0, 178.0, 156.0, 117.0, 119.0, 119.0, 121.0, 117.0, 119.0], "value": 128.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 115.0, 116.0, 153.0, 115.0, 118.0, 115.0, 115.0, 165.0, 148.0], "value": 130.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 200.0, 203.0, 209.0, 203.0, 221.0, 261.0, 196.0, 196.0, 190.0], "value": 207.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 58.0, 62.0, 86.0, 58.0, 60.0, 60.0, 63.0, 75.0, 60.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 85.0, 71.0, 70.0, 70.0, 83.0, 71.0, 72.0, 82.0], "value": 76.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 92.0, 91.0, 83.0, 137.0, 110.0, 89.0, 90.0, 92.0], "value": 96.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 102.0, 156.0, 111.0, 96.0, 98.0, 105.0, 94.0, 132.0], "value": 108.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 145.0, 144.0, 106.0, 90.0, 92.0, 110.0, 124.0, 87.0], "value": 105.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 68.0, 69.0, 67.0, 70.0, 68.0, 67.0, 68.0], "value": 67.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 62.0, 75.0, 82.0, 91.0, 84.0, 53.0, 56.0, 58.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 102.0, 112.0, 83.0, 85.0, 85.0, 83.0, 105.0, 104.0, 107.0], "value": 96.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 139.0, 141.0, 136.0, 154.0, 156.0, 152.0, 204.0, 150.0], "value": 151.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 201.0, 185.0, 187.0, 185.0, 188.0, 188.0, 203.0, 235.0, 196.0], "value": 195.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 71.0, 68.0, 77.0, 67.0, 71.0, 69.0, 66.0, 67.0], "value": 69.2, "unit": "score"}]}]} 11:28:23 INFO - Return code: 0 11:28:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:28:23 ERROR - # TBPL SUCCESS # 11:28:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:28:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:28:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:28:23 INFO - 2017-03-17 11:28:23.104 screenresolution[1662:11021] starting screenresolution argv=screenresolution get 11:28:23 INFO - 2017-03-17 11:28:23.107 screenresolution[1662:11021] Display 0: 1600x1200x32@60 11:28:23 INFO - 2017-03-17 11:28:23.113 screenresolution[1663:11024] starting screenresolution argv=screenresolution list 11:28:23 INFO - Available Modes on Display 0 11:28:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:28:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:28:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:28:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:28:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:28:23 INFO - Intel Iris: 11:28:23 INFO - Chipset Model: Intel Iris 11:28:23 INFO - Type: GPU 11:28:23 INFO - Bus: Built-In 11:28:23 INFO - VRAM (Dynamic, Max): 1536 MB 11:28:23 INFO - Vendor: Intel (0x8086) 11:28:23 INFO - Device ID: 0x0a2e 11:28:23 INFO - Revision ID: 0x0009 11:28:23 INFO - Displays: 11:28:23 INFO - Display: 11:28:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:28:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:28:23 INFO - Display Serial Number: 1600x1200 60 11:28:23 INFO - Main Display: Yes 11:28:23 INFO - Mirror: Off 11:28:23 INFO - Online: Yes 11:28:23 INFO - Rotation: Supported 11:28:23 INFO - Return code: 0 11:28:23 INFO - Running post-action listener: _resource_record_post_action 11:28:23 INFO - [mozharness: 2017-03-17 18:28:23.348109Z] Finished run-tests step (success) 11:28:23 INFO - Running post-run listener: _resource_record_post_run 11:28:23 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 38873600; Write bytes: 513416192; Read time: 242; Write time: 1605 11:28:23 INFO - TinderboxPrint: CPU usage
54.3% 11:28:23 INFO - TinderboxPrint: I/O read bytes / time
38,873,600 / 242 11:28:23 INFO - TinderboxPrint: I/O write bytes / time
513,416,192 / 1,605 11:28:23 INFO - TinderboxPrint: CPU idle
2,196.1 (45.7%) 11:28:23 INFO - TinderboxPrint: CPU system
964.8 (20.1%) 11:28:23 INFO - TinderboxPrint: CPU user
1,640.7 (34.2%) 11:28:23 INFO - TinderboxPrint: Swap in / out
249,012,224 / 0 11:28:23 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 210926080; Write bytes: 204539904; Read time: 16071; Write time: 444 11:28:23 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 31728128; Write bytes: 298075136; Read time: 185; Write time: 1130 11:28:23 INFO - Running post-run listener: _upload_blobber_files 11:28:23 INFO - Blob upload gear active. 11:28:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:28:23 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 11:28:23 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:28:23 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:28:24 INFO - (blobuploader) - INFO - Open directory for files ... 11:28:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:28:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:28:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:28:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:28:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:28:25 INFO - (blobuploader) - INFO - Done attempting. 11:28:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:28:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:28:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:28:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:28:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:28:26 INFO - (blobuploader) - INFO - Done attempting. 11:28:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:28:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:28:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:28:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:28:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:28:27 INFO - (blobuploader) - INFO - Done attempting. 11:28:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:28:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:28:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:28:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:28:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:28:29 INFO - (blobuploader) - INFO - Done attempting. 11:28:29 INFO - (blobuploader) - INFO - Iteration through files over. 11:28:29 INFO - Return code: 0 11:28:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:28:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:28:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/53623843d19c3febecd33a66c80ad0ba71410a34b59909381a8780474d796507d02cb8b2d8998a69dd5a4fb1edfd2ff7bf02faba63c30d88d4216571e357e1a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/64fec880c971f335dbc51950663e1978a6bda8eba3c743e6c75c0d7d9a470ccf5da3d89eeb08a1aadb8fc790caf326ddf94188d10df987f45440955e7907a937", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0a99b9ef8318d246b84bf6029490e6d7f861988db6a810047db2f357ca26b9bbd3cfbc05251951bef5e416c01eb7ab1074db2c6f3a0f6c37c13688eacca3db97", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b3794236232b60590c3a73486fe3262ec66e0c5f1b2be76ab81c499dad8d5c7c4555c42eecc9ba20664cdd4aaacb8c971ada45f5c0ab06ebaa12f48b309815f3"} 11:28:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:28:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:28:29 INFO - Contents: 11:28:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/53623843d19c3febecd33a66c80ad0ba71410a34b59909381a8780474d796507d02cb8b2d8998a69dd5a4fb1edfd2ff7bf02faba63c30d88d4216571e357e1a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/64fec880c971f335dbc51950663e1978a6bda8eba3c743e6c75c0d7d9a470ccf5da3d89eeb08a1aadb8fc790caf326ddf94188d10df987f45440955e7907a937", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0a99b9ef8318d246b84bf6029490e6d7f861988db6a810047db2f357ca26b9bbd3cfbc05251951bef5e416c01eb7ab1074db2c6f3a0f6c37c13688eacca3db97", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b3794236232b60590c3a73486fe3262ec66e0c5f1b2be76ab81c499dad8d5c7c4555c42eecc9ba20664cdd4aaacb8c971ada45f5c0ab06ebaa12f48b309815f3"} 11:28:29 INFO - Running post-run listener: copy_logs_to_upload_dir 11:28:29 INFO - Copying logs to upload dir... 11:28:29 INFO - mkdir: /builds/slave/test/build/upload/logs 11:28:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.751967 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-17 11:28:29.491524) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-17 11:28:29.494750) ========= 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.aAriOKVMGE/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.ioE07iaeRg/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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/Pine/sha512/53623843d19c3febecd33a66c80ad0ba71410a34b59909381a8780474d796507d02cb8b2d8998a69dd5a4fb1edfd2ff7bf02faba63c30d88d4216571e357e1a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/64fec880c971f335dbc51950663e1978a6bda8eba3c743e6c75c0d7d9a470ccf5da3d89eeb08a1aadb8fc790caf326ddf94188d10df987f45440955e7907a937", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0a99b9ef8318d246b84bf6029490e6d7f861988db6a810047db2f357ca26b9bbd3cfbc05251951bef5e416c01eb7ab1074db2c6f3a0f6c37c13688eacca3db97", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b3794236232b60590c3a73486fe3262ec66e0c5f1b2be76ab81c499dad8d5c7c4555c42eecc9ba20664cdd4aaacb8c971ada45f5c0ab06ebaa12f48b309815f3"} build_url:https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011090 build_url: 'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/53623843d19c3febecd33a66c80ad0ba71410a34b59909381a8780474d796507d02cb8b2d8998a69dd5a4fb1edfd2ff7bf02faba63c30d88d4216571e357e1a2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/64fec880c971f335dbc51950663e1978a6bda8eba3c743e6c75c0d7d9a470ccf5da3d89eeb08a1aadb8fc790caf326ddf94188d10df987f45440955e7907a937", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0a99b9ef8318d246b84bf6029490e6d7f861988db6a810047db2f357ca26b9bbd3cfbc05251951bef5e416c01eb7ab1074db2c6f3a0f6c37c13688eacca3db97", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b3794236232b60590c3a73486fe3262ec66e0c5f1b2be76ab81c499dad8d5c7c4555c42eecc9ba20664cdd4aaacb8c971ada45f5c0ab06ebaa12f48b309815f3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-17 11:28:29.529387) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 11:28:29.529714) ========= 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.aAriOKVMGE/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.ioE07iaeRg/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.005150 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 11:28:29.578001) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-17 11:28:29.578324) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-17 11:28:29.578611) ========= ========= Total master_lag: 0.19 =========