builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0301 starttime: 1492581321.01 results: success (0) buildid: 20170418162120 builduid: d3c072e1c6b5477fb42de3f295a4c9a0 revision: e6f714bb5eb2b5d6aff241ae1c46044c3563de24 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-18 22:55:21.009316) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-18 22:55:21.009768) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-18 22:55:21.010097) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-18 22:55:21.037074) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 22:55:21.037464) ========= 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.cIrvE0R0jQ/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.B05E5medgy/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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-04-18 22:55:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.4M=0.001s 2017-04-18 22:55:21 (13.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.228419 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 22:55:21.290406) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 22:55:21.290761) ========= 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.cIrvE0R0jQ/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.B05E5medgy/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.027133 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 22:55:21.339949) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-18 22:55:21.340342) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev e6f714bb5eb2b5d6aff241ae1c46044c3563de24 --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 e6f714bb5eb2b5d6aff241ae1c46044c3563de24 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cIrvE0R0jQ/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.B05E5medgy/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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-04-18 22:55:21,494 truncating revision to first 12 chars 2017-04-18 22:55:21,494 Setting DEBUG logging. 2017-04-18 22:55:21,494 attempt 1/10 2017-04-18 22:55:21,494 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/e6f714bb5eb2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-18 22:55:22,354 unpacking tar archive at: pine-e6f714bb5eb2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.138774 ========= master_lag: 0.41 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-18 22:55:22.885440) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-18 22:55:22.885861) ========= script_repo_revision: e6f714bb5eb2b5d6aff241ae1c46044c3563de24 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-18 22:55:22.886305) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-18 22:55:22.886585) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-18 22:55:22.906993) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-18 22:55:22.907458) ========= /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.cIrvE0R0jQ/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.B05E5medgy/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 22:55:23 INFO - MultiFileLogger online at 20170418 22:55:23 in /builds/slave/test 22:55:23 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 22:55:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:55:23 INFO - {'append_to_log': False, 22:55:23 INFO - 'base_work_dir': '/builds/slave/test', 22:55:23 INFO - 'blob_upload_branch': 'Pine', 22:55:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:55:23 INFO - 'branch': 'Pine', 22:55:23 INFO - 'buildbot_json_path': 'buildprops.json', 22:55:23 INFO - 'config_files': ('talos/mac_config.py',), 22:55:23 INFO - 'default_actions': ('clobber', 22:55:23 INFO - 'read-buildbot-config', 22:55:23 INFO - 'download-and-extract', 22:55:23 INFO - 'populate-webroot', 22:55:23 INFO - 'create-virtualenv', 22:55:23 INFO - 'install', 22:55:23 INFO - 'run-tests'), 22:55:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:55:23 INFO - 'download_minidump_stackwalk': True, 22:55:23 INFO - 'download_symbols': 'ondemand', 22:55:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:55:23 INFO - 'tooltool.py': '/tools/tooltool.py', 22:55:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:55:23 INFO - '/tools/misc-python/virtualenv.py')}, 22:55:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:55:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:55:23 INFO - 'gecko_profile': False, 22:55:23 INFO - 'gecko_profile_interval': 0, 22:55:23 INFO - 'installer_path': 'installer.exe', 22:55:23 INFO - 'log_level': 'info', 22:55:23 INFO - 'log_name': 'talos', 22:55:23 INFO - 'log_to_console': True, 22:55:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:55:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:55:23 INFO - 'opt_config_files': (), 22:55:23 INFO - 'pip_index': False, 22:55:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:55:23 INFO - 'cmd': ('bash', 22:55:23 INFO - '-c', 22:55:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:55:23 INFO - 'enabled': True, 22:55:23 INFO - 'halt_on_failure': False, 22:55:23 INFO - 'name': 'check_screen_resolution'},), 22:55:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:55:23 INFO - 'cmd': ('bash', 22:55:23 INFO - '-c', 22:55:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:55:23 INFO - 'enabled': True, 22:55:23 INFO - 'halt_on_failure': False, 22:55:23 INFO - 'name': 'check_screen_resolution'},), 22:55:23 INFO - 'run_cmd_checks_enabled': True, 22:55:23 INFO - 'suite': 'dromaeojs-e10s', 22:55:23 INFO - 'system_bits': '32', 22:55:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:55:23 INFO - 'title': 't-yosemite-r7-0301', 22:55:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:55:23 INFO - 'use_talos_json': True, 22:55:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:55:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:55:23 INFO - 'work_dir': 'build'} 22:55:23 INFO - [mozharness: 2017-04-19 05:55:23.168976Z] Running clobber step. 22:55:23 INFO - Running pre-action listener: _resource_record_pre_action 22:55:23 INFO - Running main action method: clobber 22:55:23 INFO - rmtree: /builds/slave/test/build 22:55:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:55:25 INFO - Running post-action listener: _resource_record_post_action 22:55:25 INFO - [mozharness: 2017-04-19 05:55:25.564400Z] Finished clobber step (success) 22:55:25 INFO - [mozharness: 2017-04-19 05:55:25.564542Z] Running read-buildbot-config step. 22:55:25 INFO - Running pre-action listener: _resource_record_pre_action 22:55:25 INFO - Running main action method: read_buildbot_config 22:55:25 INFO - Using buildbot properties: 22:55:25 INFO - { 22:55:25 INFO - "project": "", 22:55:25 INFO - "product": "firefox", 22:55:25 INFO - "script_repo_revision": "default", 22:55:25 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 22:55:25 INFO - "repository": "", 22:55:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 22:55:25 INFO - "buildid": "20170418162120", 22:55:25 INFO - "pgo_build": "False", 22:55:25 INFO - "basedir": "/builds/slave/test", 22:55:25 INFO - "buildnumber": 2, 22:55:25 INFO - "slavename": "t-yosemite-r7-0301", 22:55:25 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:55:25 INFO - "platform": "yosemite_r7", 22:55:25 INFO - "scheduler": "tests-pine-macosx64-talos", 22:55:25 INFO - "branch": "pine", 22:55:25 INFO - "repo_path": "projects/pine", 22:55:25 INFO - "revision": "e6f714bb5eb2b5d6aff241ae1c46044c3563de24", 22:55:25 INFO - "stage_platform": "macosx64", 22:55:25 INFO - "builduid": "d3c072e1c6b5477fb42de3f295a4c9a0", 22:55:25 INFO - "slavebuilddir": "test" 22:55:25 INFO - } 22:55:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 22:55:25 INFO - Running post-action listener: _resource_record_post_action 22:55:25 INFO - [mozharness: 2017-04-19 05:55:25.567899Z] Finished read-buildbot-config step (success) 22:55:25 INFO - [mozharness: 2017-04-19 05:55:25.568039Z] Running download-and-extract step. 22:55:25 INFO - Running pre-action listener: _resource_record_pre_action 22:55:25 INFO - Running main action method: download_and_extract 22:55:25 INFO - mkdir: /builds/slave/test/build/tests 22:55:25 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')]} 22:55:25 INFO - trying https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 22:55:25 INFO - Downloading https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/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 22:55:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/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 22:55:26 INFO - Downloaded 1325 bytes. 22:55:26 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:55:26 INFO - Using the following test package requirements: 22:55:26 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:55:26 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 22:55:26 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:55:26 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:55:26 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 22:55:26 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:55:26 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 22:55:26 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:55:26 INFO - u'jsshell-mac.zip'], 22:55:26 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:55:26 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 22:55:26 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:55:26 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:55:26 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 22:55:26 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:55:26 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 22:55:26 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:55:26 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 22:55:26 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:55:26 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 22:55:26 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:55:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:55:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:55:26 INFO - Fetch https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:55:28 INFO - Expected file size: 52629600 22:55:28 INFO - Obtained file size: 52629600 22:55:44 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 22:55:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:55:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:55:44 INFO - Fetch https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:55:46 INFO - Expected file size: 52629600 22:55:46 INFO - Obtained file size: 52629600 22:56:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 22:56:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:56:01 INFO - Fetch https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 22:56:03 INFO - Expected file size: 14408802 22:56:03 INFO - Obtained file size: 14408802 22:56:03 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')]} 22:56:03 INFO - trying https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:56:03 INFO - Downloading https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 22:56:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/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 22:56:05 INFO - Downloaded 71497603 bytes. 22:56:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:56:05 INFO - mkdir: /builds/slave/test/properties 22:56:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:56:05 INFO - Writing to file /builds/slave/test/properties/build_url 22:56:05 INFO - Contents: 22:56:05 INFO - build_url:https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:56:06 INFO - Running post-action listener: _resource_record_post_action 22:56:06 INFO - Running post-action listener: set_extra_try_arguments 22:56:06 INFO - [mozharness: 2017-04-19 05:56:06.451193Z] Finished download-and-extract step (success) 22:56:06 INFO - [mozharness: 2017-04-19 05:56:06.451323Z] Running populate-webroot step. 22:56:06 INFO - Running pre-action listener: _resource_record_pre_action 22:56:06 INFO - Running main action method: populate_webroot 22:56:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:56:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:56:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:56:06 INFO - '--tppagecycles', 22:56:06 INFO - '7'], 22:56:06 INFO - 'tests': ['tsvgm']}}, 22:56:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:56:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:56:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:56:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:56:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:56:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:56:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:56:06 INFO - 'talos_options': ['--disable-e10s'], 22:56:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:56:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:56:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:56:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:56:06 INFO - 'talos_options': ['--disable-e10s'], 22:56:06 INFO - 'tests': ['damp', 'tps']}, 22:56:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:56:06 INFO - 'tests': ['damp', 'tps']}, 22:56:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:56:06 INFO - 'tests': ['dromaeo_dom']}, 22:56:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:56:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:56:06 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 22:56:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:56:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:56:06 INFO - 'tests': ['a11yr', 22:56:06 INFO - 'ts_paint', 22:56:06 INFO - 'tpaint', 22:56:06 INFO - 'sessionrestore', 22:56:06 INFO - 'sessionrestore_no_auto_restore', 22:56:06 INFO - 'tabpaint']}, 22:56:06 INFO - 'other-e10s': {'tests': ['a11yr', 22:56:06 INFO - 'ts_paint', 22:56:06 INFO - 'tpaint', 22:56:06 INFO - 'sessionrestore', 22:56:06 INFO - 'sessionrestore_no_auto_restore', 22:56:06 INFO - 'tabpaint']}, 22:56:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:56:06 INFO - 'ts_paint', 22:56:06 INFO - 'tpaint', 22:56:06 INFO - 'sessionrestore', 22:56:06 INFO - 'sessionrestore_no_auto_restore', 22:56:06 INFO - 'tabpaint']}, 22:56:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:56:06 INFO - 'ts_paint', 22:56:06 INFO - 'tpaint', 22:56:06 INFO - 'sessionrestore', 22:56:06 INFO - 'sessionrestore_no_auto_restore', 22:56:06 INFO - 'tabpaint']}, 22:56:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:56:06 INFO - 'tests': ['a11yr', 22:56:06 INFO - 'ts_paint', 22:56:06 INFO - 'tpaint', 22:56:06 INFO - 'sessionrestore', 22:56:06 INFO - 'sessionrestore_no_auto_restore', 22:56:06 INFO - 'tabpaint']}, 22:56:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:56:06 INFO - 'tests': ['a11yr', 22:56:06 INFO - 'ts_paint', 22:56:06 INFO - 'tpaint', 22:56:06 INFO - 'sessionrestore', 22:56:06 INFO - 'sessionrestore_no_auto_restore', 22:56:06 INFO - 'tabpaint']}, 22:56:06 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 22:56:06 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 22:56:06 INFO - 'bloom_basic_ref']}, 22:56:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:56:06 INFO - 'tests': ['tsvgx', 22:56:06 INFO - 'tsvgr_opacity', 22:56:06 INFO - 'tart', 22:56:06 INFO - 'tscrollx', 22:56:06 INFO - 'cart', 22:56:06 INFO - 'tsvg_static']}, 22:56:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:56:06 INFO - 'tsvgr_opacity', 22:56:06 INFO - 'tart', 22:56:06 INFO - 'tscrollx', 22:56:06 INFO - 'cart', 22:56:06 INFO - 'tsvg_static']}, 22:56:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:56:06 INFO - 'talos_options': ['--disable-e10s'], 22:56:06 INFO - 'tests': ['tp5o']}, 22:56:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:56:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:56:06 INFO - 'talos_options': ['--disable-e10s', 22:56:06 INFO - '--xperf_path', 22:56:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:56:06 INFO - 'tests': ['tp5n']}, 22:56:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:56:06 INFO - 'talos_options': ['--xperf_path', 22:56:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:56:06 INFO - 'tests': ['tp5n']}}, 22:56:06 INFO - 'talos.zip': {'path': '', 22:56:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:56:06 INFO - Running post-action listener: _resource_record_post_action 22:56:06 INFO - [mozharness: 2017-04-19 05:56:06.470267Z] Finished populate-webroot step (success) 22:56:06 INFO - [mozharness: 2017-04-19 05:56:06.470378Z] Running create-virtualenv step. 22:56:06 INFO - Running pre-action listener: _resource_record_pre_action 22:56:06 INFO - Running main action method: create_virtualenv 22:56:06 INFO - Creating virtualenv /builds/slave/test/build/venv 22:56:06 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 22:56:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:56:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:56:06 INFO - Using real prefix '/tools/python27' 22:56:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:56:07 INFO - Installing distribute.............................................................................................................................................................................................done. 22:56:10 INFO - Installing pip.................done. 22:56:10 INFO - Return code: 0 22:56:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:56:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:56:10 INFO - Reading from file tmpfile_stdout 22:56:10 INFO - Output received: 22:56:10 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) 22:56:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105868b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa778cc5ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105646d30>, '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 0x10556cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10563bbe8>, '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.cIrvE0R0jQ/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.B05E5medgy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 22:56:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:56:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:56:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cIrvE0R0jQ/Render', 22:56:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:10 INFO - 'HOME': '/Users/cltbld', 22:56:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:10 INFO - 'LOGNAME': 'cltbld', 22:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:10 INFO - 'MOZ_NO_REMOTE': '1', 22:56:10 INFO - 'NO_EM_RESTART': '1', 22:56:10 INFO - 'PAGER': '/bin/cat', 22:56:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:10 INFO - 'PWD': '/builds/slave/test', 22:56:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:10 INFO - 'SHELL': '/bin/bash', 22:56:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B05E5medgy/Listeners', 22:56:10 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 22:56:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:10 INFO - 'USER': 'cltbld', 22:56:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:10 INFO - 'XPC_FLAGS': '0x0', 22:56:10 INFO - 'XPC_SERVICE_NAME': '0', 22:56:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:56:10 INFO - Cleaning up... 22:56:10 INFO - Return code: 0 22:56:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105868b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa778cc5ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105646d30>, '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 0x10556cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10563bbe8>, '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.cIrvE0R0jQ/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.B05E5medgy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 22:56:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:56:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:56:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cIrvE0R0jQ/Render', 22:56:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:10 INFO - 'HOME': '/Users/cltbld', 22:56:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:10 INFO - 'LOGNAME': 'cltbld', 22:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:10 INFO - 'MOZ_NO_REMOTE': '1', 22:56:10 INFO - 'NO_EM_RESTART': '1', 22:56:10 INFO - 'PAGER': '/bin/cat', 22:56:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:10 INFO - 'PWD': '/builds/slave/test', 22:56:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:10 INFO - 'SHELL': '/bin/bash', 22:56:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B05E5medgy/Listeners', 22:56:10 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 22:56:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:10 INFO - 'USER': 'cltbld', 22:56:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:10 INFO - 'XPC_FLAGS': '0x0', 22:56:10 INFO - 'XPC_SERVICE_NAME': '0', 22:56:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:11 INFO - Downloading/unpacking psutil>=3.1.1 22:56:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:56:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:56:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:56:14 INFO - Installing collected packages: psutil 22:56:14 INFO - Running setup.py install for psutil 22:56:14 INFO - building 'psutil._psutil_osx' extension 22:56:14 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 22:56:15 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 22:56:15 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 22:56:15 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 22:56:15 INFO - building 'psutil._psutil_posix' extension 22:56:15 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 22:56:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:56:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:56:15 INFO - ^ 22:56:15 INFO - 1 warning generated. 22:56:15 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 22:56:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:56:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:56:15 INFO - Successfully installed psutil 22:56:15 INFO - Cleaning up... 22:56:15 INFO - Return code: 0 22:56:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:56: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')]} 22:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56: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')]} 22:56:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105868b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa778cc5ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105646d30>, '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 0x10556cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10563bbe8>, '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.cIrvE0R0jQ/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.B05E5medgy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 22:56:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:56:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:56:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cIrvE0R0jQ/Render', 22:56:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:15 INFO - 'HOME': '/Users/cltbld', 22:56:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:15 INFO - 'LOGNAME': 'cltbld', 22:56:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:15 INFO - 'MOZ_NO_REMOTE': '1', 22:56:15 INFO - 'NO_EM_RESTART': '1', 22:56:15 INFO - 'PAGER': '/bin/cat', 22:56:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:15 INFO - 'PWD': '/builds/slave/test', 22:56:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:15 INFO - 'SHELL': '/bin/bash', 22:56:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B05E5medgy/Listeners', 22:56:15 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 22:56:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:15 INFO - 'USER': 'cltbld', 22:56:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:15 INFO - 'XPC_FLAGS': '0x0', 22:56:15 INFO - 'XPC_SERVICE_NAME': '0', 22:56:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:56:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:56:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:56:18 INFO - Installing collected packages: mozsystemmonitor 22:56:18 INFO - Running setup.py install for mozsystemmonitor 22:56:18 INFO - Successfully installed mozsystemmonitor 22:56:18 INFO - Cleaning up... 22:56:18 INFO - Return code: 0 22:56:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:56:18 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')]} 22:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:19 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')]} 22:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:19 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 0x105868b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa778cc5ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105646d30>, '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 0x10556cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10563bbe8>, '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.cIrvE0R0jQ/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.B05E5medgy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 22:56:19 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 22:56:19 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 22:56:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cIrvE0R0jQ/Render', 22:56:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:19 INFO - 'HOME': '/Users/cltbld', 22:56:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:19 INFO - 'LOGNAME': 'cltbld', 22:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:19 INFO - 'MOZ_NO_REMOTE': '1', 22:56:19 INFO - 'NO_EM_RESTART': '1', 22:56:19 INFO - 'PAGER': '/bin/cat', 22:56:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:19 INFO - 'PWD': '/builds/slave/test', 22:56:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:19 INFO - 'SHELL': '/bin/bash', 22:56:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B05E5medgy/Listeners', 22:56:19 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 22:56:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:19 INFO - 'USER': 'cltbld', 22:56:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:19 INFO - 'XPC_FLAGS': '0x0', 22:56:19 INFO - 'XPC_SERVICE_NAME': '0', 22:56:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:19 INFO - Downloading/unpacking jsonschema==2.5.1 22:56:19 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 22:56:19 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 22:56:19 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 22:56:19 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 22:56:19 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 22:56:19 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 22:56:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:56:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:22 INFO - Downloading functools32-3.2.3-2.tar.gz 22:56:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:56:23 INFO - warning: no files found matching '*.txt' 22:56:23 INFO - no previously-included directories found matching 'build' 22:56:23 INFO - no previously-included directories found matching 'dist' 22:56:23 INFO - no previously-included directories found matching '.git*' 22:56:23 INFO - Installing collected packages: jsonschema, functools32 22:56:23 INFO - Running setup.py install for functools32 22:56:23 INFO - warning: no files found matching '*.txt' 22:56:23 INFO - no previously-included directories found matching 'build' 22:56:23 INFO - no previously-included directories found matching 'dist' 22:56:23 INFO - no previously-included directories found matching '.git*' 22:56:23 INFO - Successfully installed jsonschema functools32 22:56:23 INFO - Cleaning up... 22:56:23 INFO - Return code: 0 22:56:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:56:23 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')]} 22:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:23 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')]} 22:56:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:23 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 0x105868b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa778cc5ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105646d30>, '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 0x10556cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10563bbe8>, '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.cIrvE0R0jQ/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.B05E5medgy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 22:56:23 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 22:56:23 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 22:56:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cIrvE0R0jQ/Render', 22:56:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:23 INFO - 'HOME': '/Users/cltbld', 22:56:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:23 INFO - 'LOGNAME': 'cltbld', 22:56:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:23 INFO - 'MOZ_NO_REMOTE': '1', 22:56:23 INFO - 'NO_EM_RESTART': '1', 22:56:23 INFO - 'PAGER': '/bin/cat', 22:56:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:23 INFO - 'PWD': '/builds/slave/test', 22:56:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:23 INFO - 'SHELL': '/bin/bash', 22:56:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B05E5medgy/Listeners', 22:56:23 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 22:56:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:23 INFO - 'USER': 'cltbld', 22:56:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:23 INFO - 'XPC_FLAGS': '0x0', 22:56:23 INFO - 'XPC_SERVICE_NAME': '0', 22:56:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:56:23 INFO - Cleaning up... 22:56:23 INFO - Return code: 0 22:56:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:56:23 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')]} 22:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:23 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')]} 22:56:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:23 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 0x105868b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa778cc5ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105646d30>, '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 0x10556cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10563bbe8>, '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.cIrvE0R0jQ/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.B05E5medgy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 22:56:23 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 22:56:23 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 22:56:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cIrvE0R0jQ/Render', 22:56:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:23 INFO - 'HOME': '/Users/cltbld', 22:56:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:23 INFO - 'LOGNAME': 'cltbld', 22:56:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:23 INFO - 'MOZ_NO_REMOTE': '1', 22:56:23 INFO - 'NO_EM_RESTART': '1', 22:56:23 INFO - 'PAGER': '/bin/cat', 22:56:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:23 INFO - 'PWD': '/builds/slave/test', 22:56:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:23 INFO - 'SHELL': '/bin/bash', 22:56:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B05E5medgy/Listeners', 22:56:23 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 22:56:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:23 INFO - 'USER': 'cltbld', 22:56:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:23 INFO - 'XPC_FLAGS': '0x0', 22:56:23 INFO - 'XPC_SERVICE_NAME': '0', 22:56:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:23 INFO - Downloading/unpacking blobuploader==1.2.4 22:56:23 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 22:56:23 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 22:56:23 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 22:56:23 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 22:56:23 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 22:56:23 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 22:56:26 INFO - Downloading blobuploader-1.2.4.tar.gz 22:56:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:56:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:56:26 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 22:56:26 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 22:56:26 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 22:56:26 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 22:56:26 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 22:56:26 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 22:56:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:56:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:27 INFO - Downloading docopt-0.6.1.tar.gz 22:56:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:56:27 INFO - Installing collected packages: blobuploader, requests, docopt 22:56:27 INFO - Running setup.py install for blobuploader 22:56:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:56:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:56:28 INFO - Running setup.py install for requests 22:56:28 INFO - Running setup.py install for docopt 22:56:28 INFO - Successfully installed blobuploader requests docopt 22:56:28 INFO - Cleaning up... 22:56:28 INFO - Return code: 0 22:56:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:56:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:56:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:28 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 0x105868b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa778cc5ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105646d30>, '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 0x10556cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10563bbe8>, '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.cIrvE0R0jQ/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.B05E5medgy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 22:56:28 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 22:56:28 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 22:56:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cIrvE0R0jQ/Render', 22:56:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:28 INFO - 'HOME': '/Users/cltbld', 22:56:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:28 INFO - 'LOGNAME': 'cltbld', 22:56:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:28 INFO - 'MOZ_NO_REMOTE': '1', 22:56:28 INFO - 'NO_EM_RESTART': '1', 22:56:28 INFO - 'PAGER': '/bin/cat', 22:56:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:28 INFO - 'PWD': '/builds/slave/test', 22:56:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:28 INFO - 'SHELL': '/bin/bash', 22:56:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B05E5medgy/Listeners', 22:56:28 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 22:56:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:28 INFO - 'USER': 'cltbld', 22:56:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:28 INFO - 'XPC_FLAGS': '0x0', 22:56:28 INFO - 'XPC_SERVICE_NAME': '0', 22:56:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:56:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-b_0PNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:56:29 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-iuFyy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:56:29 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-FiineJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:56:29 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-RlKuRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:56:29 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-gE32Dv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:56:29 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-sQpcoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:56:29 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-K5zbwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:56:29 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-ZPFw0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:56:29 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-S9ZVqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:56:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:56:29 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-chohkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:56:30 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-YEP0Sz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:56:30 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-xQ6GfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:56:30 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-ICiKWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:56:30 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-2EWWgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:56:30 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-X0l2A8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:56:30 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-mD2QKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:56:30 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-lkCYHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:56:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:56:30 INFO - Running setup.py install for manifestparser 22:56:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:56:30 INFO - Running setup.py install for mozcrash 22:56:31 INFO - Running setup.py install for mozdebug 22:56:31 INFO - Running setup.py install for mozdevice 22:56:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:56:31 INFO - Running setup.py install for mozfile 22:56:31 INFO - Running setup.py install for mozhttpd 22:56:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:56:31 INFO - Running setup.py install for mozinfo 22:56:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:56:31 INFO - Running setup.py install for mozInstall 22:56:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:56:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:56:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:56:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:56:31 INFO - Running setup.py install for mozleak 22:56:32 INFO - Running setup.py install for mozlog 22:56:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:56:32 INFO - Running setup.py install for moznetwork 22:56:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:56:32 INFO - Running setup.py install for mozprocess 22:56:32 INFO - Running setup.py install for mozprofile 22:56:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:56:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:56:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:56:32 INFO - Running setup.py install for mozrunner 22:56:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:56:32 INFO - Running setup.py install for mozscreenshot 22:56:33 INFO - Running setup.py install for moztest 22:56:33 INFO - Running setup.py install for mozversion 22:56:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:56:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:56:33 INFO - Cleaning up... 22:56:33 INFO - Return code: 0 22:56:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:56:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:56:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:56:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:33 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 0x105868b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa778cc5ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105646d30>, '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 0x10556cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10563bbe8>, '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.cIrvE0R0jQ/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.B05E5medgy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 22:56:33 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 22:56:33 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 22:56:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cIrvE0R0jQ/Render', 22:56:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:33 INFO - 'HOME': '/Users/cltbld', 22:56:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:33 INFO - 'LOGNAME': 'cltbld', 22:56:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:33 INFO - 'MOZ_NO_REMOTE': '1', 22:56:33 INFO - 'NO_EM_RESTART': '1', 22:56:33 INFO - 'PAGER': '/bin/cat', 22:56:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:33 INFO - 'PWD': '/builds/slave/test', 22:56:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:33 INFO - 'SHELL': '/bin/bash', 22:56:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B05E5medgy/Listeners', 22:56:33 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 22:56:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:33 INFO - 'USER': 'cltbld', 22:56:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:33 INFO - 'XPC_FLAGS': '0x0', 22:56:33 INFO - 'XPC_SERVICE_NAME': '0', 22:56:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:56:33 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Uj3nKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:56:33 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)) 22:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:56:33 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Q9ZLeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:56:33 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)) 22:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:56:33 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-xyc5fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:56:33 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)) 22:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:56:33 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-3ryCY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:56:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 22:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:56:34 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-hWlNEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:56:34 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)) 22:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:56:34 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-baBDY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:56:34 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)) 22:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:56:34 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-dMK4b6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:56:34 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)) 22:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:56:34 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-poIkAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:56:34 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)) 22:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:56:34 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-3kszpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:56:34 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)) 22:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:56:34 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-lsLbFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:56:34 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)) 22:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:56:34 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-sRxebw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:56:34 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)) 22:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:56:34 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-nlCWsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:56:34 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)) 22:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:56:34 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-t8LeBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:56:35 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)) 22:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:56:35 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-BV0W2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:56:35 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)) 22:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:56:35 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-yyiv7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:56:35 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)) 22:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:56:35 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-r87ygX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:56:35 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)) 22:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:56:35 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-T8Lre6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:56:35 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)) 22:56:35 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)) 22:56:35 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)) 22:56:35 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)) 22:56:35 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:56:35 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:56:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:38 INFO - Downloading blessings-1.6.tar.gz 22:56:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:56:38 INFO - Installing collected packages: blessings 22:56:38 INFO - Running setup.py install for blessings 22:56:38 INFO - Successfully installed blessings 22:56:38 INFO - Cleaning up... 22:56:38 INFO - Return code: 0 22:56:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:56:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:56:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:56:38 INFO - Reading from file tmpfile_stdout 22:56:38 INFO - Current package versions: 22:56:38 INFO - blessings == 1.6 22:56:38 INFO - blobuploader == 1.2.4 22:56:38 INFO - docopt == 0.6.1 22:56:38 INFO - functools32 == 3.2.3-2 22:56:38 INFO - jsonschema == 2.5.1 22:56:38 INFO - manifestparser == 1.1 22:56:38 INFO - mozInstall == 1.12 22:56:38 INFO - mozcrash == 1.0 22:56:38 INFO - mozdebug == 0.1 22:56:38 INFO - mozdevice == 0.50 22:56:38 INFO - mozfile == 1.2 22:56:38 INFO - mozhttpd == 0.7 22:56:38 INFO - mozinfo == 0.9 22:56:38 INFO - mozleak == 0.1 22:56:38 INFO - mozlog == 3.4 22:56:38 INFO - moznetwork == 0.27 22:56:38 INFO - mozprocess == 0.25 22:56:38 INFO - mozprofile == 0.28 22:56:38 INFO - mozrunner == 6.13 22:56:38 INFO - mozscreenshot == 0.1 22:56:38 INFO - mozsystemmonitor == 0.3 22:56:38 INFO - moztest == 0.8 22:56:38 INFO - mozversion == 1.4 22:56:38 INFO - psutil == 3.1.1 22:56:38 INFO - requests == 1.2.3 22:56:38 INFO - wsgiref == 0.1.2 22:56:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:56:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:38 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 0x105868b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa778cc5ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105646d30>, '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 0x10556cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10563bbe8>, '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.cIrvE0R0jQ/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.B05E5medgy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 22:56:38 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 22:56:38 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 22:56:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cIrvE0R0jQ/Render', 22:56:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:38 INFO - 'HOME': '/Users/cltbld', 22:56:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:38 INFO - 'LOGNAME': 'cltbld', 22:56:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:38 INFO - 'MOZ_NO_REMOTE': '1', 22:56:38 INFO - 'NO_EM_RESTART': '1', 22:56:38 INFO - 'PAGER': '/bin/cat', 22:56:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:38 INFO - 'PWD': '/builds/slave/test', 22:56:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:38 INFO - 'SHELL': '/bin/bash', 22:56:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B05E5medgy/Listeners', 22:56:38 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 22:56:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:38 INFO - 'USER': 'cltbld', 22:56:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:38 INFO - 'XPC_FLAGS': '0x0', 22:56:38 INFO - 'XPC_SERVICE_NAME': '0', 22:56:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:38 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)) 22:56:38 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)) 22:56:38 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)) 22:56:38 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)) 22:56:38 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)) 22:56:38 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)) 22:56:38 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)) 22:56:38 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)) 22:56:38 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)) 22:56:38 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)) 22:56:38 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)) 22:56:38 INFO - Cleaning up... 22:56:38 INFO - Return code: 0 22:56:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:56:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105868b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10586b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa778cc5ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105646d30>, '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 0x10556cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10563bbe8>, '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.cIrvE0R0jQ/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.B05E5medgy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 22:56:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:56:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:56:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cIrvE0R0jQ/Render', 22:56:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:38 INFO - 'HOME': '/Users/cltbld', 22:56:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:38 INFO - 'LOGNAME': 'cltbld', 22:56:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:38 INFO - 'MOZ_NO_REMOTE': '1', 22:56:38 INFO - 'NO_EM_RESTART': '1', 22:56:38 INFO - 'PAGER': '/bin/cat', 22:56:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:38 INFO - 'PWD': '/builds/slave/test', 22:56:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:38 INFO - 'SHELL': '/bin/bash', 22:56:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B05E5medgy/Listeners', 22:56:38 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 22:56:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:38 INFO - 'USER': 'cltbld', 22:56:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:38 INFO - 'XPC_FLAGS': '0x0', 22:56:38 INFO - 'XPC_SERVICE_NAME': '0', 22:56:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:56:39 INFO - Cleaning up... 22:56:39 INFO - Return code: 0 22:56:39 INFO - Running post-action listener: _resource_record_post_action 22:56:39 INFO - Running post-action listener: _start_resource_monitoring 22:56:39 INFO - Starting resource monitoring. 22:56:39 INFO - [mozharness: 2017-04-19 05:56:39.274346Z] Finished create-virtualenv step (success) 22:56:39 INFO - [mozharness: 2017-04-19 05:56:39.275358Z] Running install step. 22:56:39 INFO - Running pre-action listener: _resource_record_pre_action 22:56:39 INFO - Running main action method: install 22:56:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:56:39 INFO - Reading from file tmpfile_stdout 22:56:39 INFO - Detecting whether we're running mozinstall >=1.0... 22:56:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:56:39 INFO - Reading from file tmpfile_stdout 22:56:39 INFO - Output received: 22:56:39 INFO - Usage: mozinstall [options] installer 22:56:39 INFO - Options: 22:56:39 INFO - -h, --help show this help message and exit 22:56:39 INFO - -d DEST, --destination=DEST 22:56:39 INFO - Directory to install application into. [default: 22:56:39 INFO - "/builds/slave/test"] 22:56:39 INFO - --app=APP Application being installed. [default: firefox] 22:56:39 INFO - mkdir: /builds/slave/test/build/application 22:56:39 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'] 22:56:39 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 22:56:59 INFO - Reading from file tmpfile_stdout 22:56:59 INFO - Output received: 22:56:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:56:59 INFO - Running post-action listener: _resource_record_post_action 22:56:59 INFO - [mozharness: 2017-04-19 05:56:59.609368Z] Finished install step (success) 22:56:59 INFO - [mozharness: 2017-04-19 05:56:59.609520Z] Running run-tests step. 22:56:59 INFO - Running pre-action listener: _resource_record_pre_action 22:56:59 INFO - Running main action method: run_tests 22:56:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:56:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:56:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:56:59 INFO - 2017-04-18 22:56:59.623 screenresolution[1628:7413] starting screenresolution argv=screenresolution get 22:56:59 INFO - 2017-04-18 22:56:59.626 screenresolution[1628:7413] Display 0: 1600x1200x32@60 22:56:59 INFO - 2017-04-18 22:56:59.632 screenresolution[1629:7416] starting screenresolution argv=screenresolution list 22:56:59 INFO - Available Modes on Display 0 22:56:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:56:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:56:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:56:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:56:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:56:59 INFO - Intel Iris: 22:56:59 INFO - Chipset Model: Intel Iris 22:56:59 INFO - Type: GPU 22:56:59 INFO - Bus: Built-In 22:56:59 INFO - VRAM (Dynamic, Max): 1536 MB 22:56:59 INFO - Vendor: Intel (0x8086) 22:56:59 INFO - Device ID: 0x0a2e 22:56:59 INFO - Revision ID: 0x0009 22:56:59 INFO - Displays: 22:56:59 INFO - Display: 22:56:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:56:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:56:59 INFO - Display Serial Number: 1600x1200 60 22:56:59 INFO - Main Display: Yes 22:56:59 INFO - Mirror: Off 22:56:59 INFO - Online: Yes 22:56:59 INFO - Rotation: Supported 22:56:59 INFO - Return code: 0 22:56:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:56:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:56:59 INFO - Python 2.7.3 22:56:59 INFO - Return code: 0 22:56:59 INFO - grabbing minidump binary from tooltool 22:56:59 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')]} 22:56:59 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 0x105646d30>, '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 0x10556cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10563bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:56:59 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 22:56:59 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 22:56:59 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 22:56:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:56:59 INFO - Return code: 0 22:56:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:56:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:56:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:56:59 INFO - ENV: RUST_BACKTRACE is now 1 22:56:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:56:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:56:59 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/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--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 22:56:59 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/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0301 --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 22:56:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cIrvE0R0jQ/Render', 22:56:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:59 INFO - 'HOME': '/Users/cltbld', 22:56:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:59 INFO - 'LOGNAME': 'cltbld', 22:56:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:56:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:59 INFO - 'MOZ_NO_REMOTE': '1', 22:56:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:56:59 INFO - 'NO_EM_RESTART': '1', 22:56:59 INFO - 'PAGER': '/bin/cat', 22:56:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:59 INFO - 'PWD': '/builds/slave/test', 22:56:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:56:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:59 INFO - 'RUST_BACKTRACE': '1', 22:56:59 INFO - 'SHELL': '/bin/bash', 22:56:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B05E5medgy/Listeners', 22:56:59 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 22:56:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:59 INFO - 'USER': 'cltbld', 22:56:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:59 INFO - 'XPC_FLAGS': '0x0', 22:56:59 INFO - 'XPC_SERVICE_NAME': '0', 22:56:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:59 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/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--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 22:57:00 INFO - mozversion application_buildid: 20170418162120 22:57:00 INFO - mozversion application_changeset: e6f714bb5eb2b5d6aff241ae1c46044c3563de24 22:57:00 INFO - mozversion application_display_name: Nightly 22:57:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:57:00 INFO - mozversion application_name: Firefox 22:57:00 INFO - mozversion application_remotingname: firefox 22:57:00 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 22:57:00 INFO - mozversion application_vendor: Mozilla 22:57:00 INFO - mozversion application_version: 55.0a1 22:57:00 INFO - mozversion platform_buildid: 20170418162120 22:57:00 INFO - mozversion platform_changeset: e6f714bb5eb2b5d6aff241ae1c46044c3563de24 22:57:00 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 22:57:00 INFO - mozversion platform_version: 55.0a1 22:57:00 INFO - using testdate: 1492581420 22:57:00 INFO - actual date: 1492581420 22:57:00 INFO - starting webserver on 'localhost:49240' 22:57:00 INFO - SUITE-START | Running 2 tests 22:57:00 INFO - TEST-START | dromaeo_css 22:57:00 INFO - operating with platform_type : mac_ 22:57:00 INFO - Initialising browser for dromaeo_css test... 22:57:00 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpQ6qa1Q/profile http://localhost:49240/getInfo.html) 22:57:03 INFO - PID 1639 | 2017-04-18 22:57:03.159 plugin-container[1641:7601] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 22:57:03 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:57:03 INFO - PID 1639 | 2017-04-18 22:57:03.162 plugin-container[1641:7601] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.CFPasteboardClient' 22:57:03 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:57:03 INFO - PID 1639 | 2017-04-18 22:57:03.162 plugin-container[1641:7601] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:57:03 INFO - PID 1639 | __metrics Screen width/height:1600/1200 22:57:03 INFO - PID 1639 | colorDepth:24 22:57:03 INFO - PID 1639 | Browser inner width/height: 1024/689 22:57:03 INFO - PID 1639 | __metrics 22:57:03 ERROR - PID 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3385: TypeError: this.tabs is undefined 22:57:04 INFO - TEST-INFO | 1639: exit 0 22:57:04 INFO - Browser initialized. 22:57:04 INFO - Running cycle 1/1 for dromaeo_css test... 22:57:04 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpQ6qa1Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:57:06 INFO - PID 1642 | 2017-04-18 22:57:06.649 plugin-container[1643:7744] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4f, name = 'com.apple.tsm.portname' 22:57:06 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:57:06 INFO - PID 1642 | 2017-04-18 22:57:06.649 plugin-container[1643:7744] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 22:57:06 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:57:06 INFO - PID 1642 | 2017-04-18 22:57:06.649 plugin-container[1643:7744] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:57:16 INFO - PID 1642 | RSS: Main: 237146112 22:57:16 INFO - PID 1642 | 23:00:20 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 23:00:22 INFO - PID 1642 | RSS: Main: 252538880 23:00:22 INFO - PID 1642 | 23:02:04 INFO - PID 1642 | ************************* 23:02:04 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 23:02:04 INFO - PID 1642 | 23:02:04 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:02:04 INFO - PID 1642 | Full stack: 23:02:04 INFO - PID 1642 | ************************* 23:02:04 INFO - PID 1642 | ************************* 23:02:04 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 23:02:04 INFO - PID 1642 | 23:02:04 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:02:04 INFO - PID 1642 | Full stack: 23:02:04 INFO - PID 1642 | ************************* 23:02:04 INFO - PID 1642 | ************************* 23:02:04 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 23:02:04 INFO - PID 1642 | 23:02:04 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:02:04 INFO - PID 1642 | Full stack: 23:02:04 INFO - PID 1642 | ************************* 23:02:04 INFO - PID 1642 | ************************* 23:02:04 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 23:02:04 INFO - PID 1642 | 23:02:04 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:02:04 INFO - PID 1642 | Full stack: 23:02:04 INFO - PID 1642 | ************************* 23:03:24 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 23:03:24 INFO - PID 1642 | RSS: Main: 256737280 23:03:24 INFO - PID 1642 | 23:06:26 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 23:06:27 INFO - PID 1642 | RSS: Main: 265650176 23:06:27 INFO - PID 1642 | 23:09:29 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 23:09:29 INFO - PID 1642 | RSS: Main: 259100672 23:09:29 INFO - PID 1642 | 23:12:32 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 23:12:33 INFO - PID 1642 | RSS: Main: 269455360 23:12:33 INFO - PID 1642 | 23:15:35 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 23:15:35 INFO - PID 1642 | RSS: Main: 264421376 23:15:35 INFO - PID 1642 | 23:15:35 INFO - PID 1642 | __start_tp_report 23:15:35 INFO - PID 1642 | _x_x_mozilla_page_load 23:15:35 INFO - PID 1642 | _x_x_mozilla_page_load_details 23:15:35 INFO - PID 1642 | |i|pagename|runs| 23:15:35 INFO - PID 1642 | |0;dojo.html;3136.200268931422;3567.965290065881;3592.91017724557;3597.424412094065;3602.513660655811;4757.357756702844;5798.3042034955815;6116.327204007555;6145.72344244509;6250.843728906774;6141.631502109876;6154.769046190758;6169.814905552829;6225.377462253784;6241.407066328703;4261.573842615745;5443.727813609334;5454.34547854258;5457.317835270591;5499.945000549983;5403.297571315724;5424.783008679648;5485.780568777244;5586.469285417878;5597.412271711472;5276.208568714724;5379.435159308256;5437.13005919051;5500.22996780451;5506.531944784678;4439.4228750262255;5329.706866122362;5368.543673787733;5451.37309209442;5490.423505531901;5332.200169974496;5369.597280203961;5380.300560927094;5382.111951527993;5397.68042653769;4688.780916961615;6126.744017476407;6181.3818618138275;6204.410580994799;6224.937750622481;6403.391677790602;6503.057056726795;6568.408843204134;6675.7997260082275;6678.732850686015;6743.831404214885;6757.729690812411;6772.187337519529;6783.864322713518;6799.082123913257;6242.094896240111;6289.528285378615;6301.52738544611;6317.842053948596;6321.905171422386;6632.303608121078;6699.933000670028;6796.52424330302;6825.419839313714;6878.38094571491;6263.154474146023;6398.712057957403;6473.190851143607;6612.7354905804195;6658.101156343929;5317.441668624816;6596.406323430914;6720.395164435225;6773.153355830521;6794.864102717918;6663.167104111986;6674.899876501856;6675.632840193739;6678.165229346332;6697.8995315070315;5699;6350.872982540416;6476.449501792306;6629.734810607618;6707.564008339442;4942.752862356868;5366;5381;5381.3811411687375;5430.348358197041;4077.7757223352405;4770.61835053195;4774.164521208774;4784.425868895685;4785.210440277385;6126.23422072241;6171.197744293303;6256.562040657202;6256.874862502816;6306.747730090745;6695.330466953265;6733.595984240863;6773.525853190229;6784.151981002375;6787.898181527281;6212.316645169028;6250.6874656267;6276.027215781562;6289.150964619717;6311.526635502356;4998.850034498971;5719.370869204384;5955.434233747787;5981.457880400899;5994.100884867305;6690.264070952094;6743.797686069426;6798.660066996729;6829.419499342612;6839.110915580943;5759.539236860957;6750.088738020305;6774.187097548326;6778.084958530535;6799.150106236721;6820.079289295882;6837.247902730795;6862.3137686230975;6900.413464855544;6900.688869114852;6270.9372906270355;6386.329435409401;6496.155499784998;6584.670766461753;6678.098456708282;5647.463490968352;6753.3921947025;6798.456123510206;6818;6824.590524568542;6143.972489078683;6377.904331434939;6580.407763301326;6591.077249185022;6595.769148079794;6496.317886622026;6581.3418658135715;6599.60402375859;6600.60396376219;6606.768763093269;6193.628382296896;6269.717862696099;6328.525360597881;6389.7124629390855;6486.189226346707;5767.452092051249;6577.934220657732;6580.078788969644;6609.702563384563;6611.504137189634;6396.232452105591;6429.839254018687;6442.2591401989375;6463.15978922737;6474.1907261592305 23:15:35 INFO - PID 1642 | |1;ext.html;743;783.902012248469;817.9959100204496;1249.5688987299384;1254.1973137192192;37468.812655936716;38012.23975520488;38061.429078563844;38085.04787380317;38233.85298441048;38610.420843687294;38668.61331386685;38842.61157388425;38876.80561597188;38976.610233897656;8132.064812546559;8260.628271727757;8553.230209281164;8838.513881736502;8865;8634.222919937205;8656.047834738174;8708.346873984476;8729.432586881863;8734.301255899512;8654.87017694735;8745.562721863913;8750.912490875073;8764.824703505927;8778.473291602524;8695.21743043126;8726.86550748405;8736.781580460476;8742.213200811926;8766.167214114648;8495.702650407206;8545.31637469001;8554.52950087745;8572.657093716243;8580.785480362978;38308.80845595782;38380;38381.424278635845;38414.615853841664;38475.42286865699;3620.529331186955;3654.26914617075;3655.780653160819;3660.0483874192637;3665.596784353719;30377.392452151053;30472.695273047208;30529;30551.694483055107;30572;3172.476541370671;3216.2281052547455;3217.9034628961167;3230.305484320859;3233.0785726067957;256789;294498;301438.4928075346;302504;302981;48533.7866553339;48578;48644.27033594428;48675.26987095196;48784.51215487871;41563.58436415597;50085.749571252636;50284.742881427526;51677.741611291705;52023.21965170526;13609.31953402326;13622.455101795817;13706;13712.588622341145;13724.65688357799;9408.858867116998;10949.83575246372;10963.342199467897;11053.336799792038;11088.33469991787;13111.951043916655;13132.868671313356;13143.211407315592;13189.868101319056;13387.732245355039;4902.651770763068;5108.948910510921;5915.911261331084;5929.436703513159;5986.49114825236;942.0814705661925;946.8863736351682;995.5370752599931;1015.5937624950079;1025.5590096258532;23338.183163589194;24052.15817446381;24474.26577202687;24561.508769824504;24656.383590410383;985.7486340983004;1011.5469985285653;1061.0662616897234;1125.6904351303458;1170.5610396101463;10694.625688101034;10968.451577421098;11099.556017759362;12216.633500994983;12680;292690;294356;296072;296366;298073;43742.78128609336;54475;54677.179842302394;54816;54975;1462.795208670787;1514.780356848251;1557.9610509737347;1559.9454019109276;1571.5599632103028;22127.33617991463;23946;23959.16142934989;24103.397415064763;24233.757662423854;38546;38724.80637596794;38963.610363896;39016;39069.41395879007;210969;261015;261571;261676.3832361652;261877;34550.65449345474;34621.30757384788;34971;35689.82155089208;36572.90281291566;44411.777941110086;44936.65190044304;44970.87572810706;45013;45022;26682.332941676614;28928.359563108625;29181;30079.398412032075;31338.21654458657;26932.46135077327;27402.725972740816;28386.290342741595;30948.69051309548;31353.686463135076;27862.721372786822;36590;37038.62961370352;37351.43972840464;37362.8131859339;30716.846415768672;30984.760609575365;31485;31775.364492709556;31880;20106.396808095782;22669.546609068057;23321.067157314195;24477.632835507124;24590 23:15:35 INFO - PID 1642 | |2;jquery.html;407.70237726459686;425.3768229543718;426.1987463568491;443.3504915299086;449.3439578215805;21023;21105.47236319093;21700.89149554252;21976.230831920886;22218.222362217326;16248.675026499463;21608.675869861963;22349.100326472268;22530.85956834098;22640.320790376274;5649.858753531174;5814.069748840187;6183.319834818167;6217.502599792006;6228.781992630259;5253.7373131343475;6022.488088512459;6028.577999540034;6103.786367477139;6112.847178820521;6031.125486804411;6060.666663333515;6092.908606370908;6093.908591371133;6124.724387402578;4990.351254336932;5930.258717660293;6047.183630209954;6052.848678783065;6064.757409703607;5238;5347.438518955531;5369.838904832861;5376.865578360573;5416.1063424534905;26995.325116872038;27161.592576111376;27291.727082729118;27374;27638.618069096465;1910.2645481489587;2177.4665207024223;2210.7347118345742;2211.9336419907427;2214.070090561968;18233.635327293512;18239.084896085853;18456.615753818383;18463.446096617125;18553.44339669812;1963.2539634938632;2179.9972012873936;2191.747948985871;2238.5746708125566;2239.384169353441;156950;159399;160307;161408;162636.74726505403;12535.686607834878;12587.181833180814;12728.427220775087;12853;12882.80675789864;16226.350945962266;16451;16476.83523164777;16485.670286594443;16488.587785305463;8602.87095693565;8762.737117886474;8916.465012099296;9018.729438116867;9034.412763170372;8369.539675317794;8375.74872753806;8417.200365965224;8583.442076265022;8616.956915215509;7883.802904927423;7977.840443191104;7989.121196668362;8084.959575202086;8122.431429799975;2141;2164.7835216478543;2166.1876796201423;2169.316665250441;2174.3476956912864;962.0091305954878;973.7341456107033;984.4487087231173;1023.0587859169582;1026.6201505443034;15941.202939852961;17582.120893955253;19815;20177.120150163002;20936.057877395553;1001.8196724589498;1011.9038691324313;1032.7418145463635;1043.5512729526224;1044.8215470743432;7793.727219547289;10279.486025698834;10428.374297542172;10817.296875703054;10877.619283325204;538919;540062.2996885069;588601;596093;597091;103241;119948;124791;128759.06861397087;131047.9999999981;1065.9733506662396;1106.2753896198003;1203;1203.9458224379923;1260.2249616485794;14674.853251467348;18385.908070460096;21257.255996040065;21259.46851328729;21267.255646052316;53426;54925.45074549203;56462.717686411306;56988.145177821534;57327.71336143293;70940.64529677319;76838;81265.59367203363;82164;83294;7138.750143744944;8852.3803333766;8857.955710221666;8976.910230897869;8996.460212387277;12750.171238869443;13113;13936.233507156798;14269.072510286798;14403.927980360031;11539.596114135966;11615.593454229062;12050.698732531757;12127.939360303142;12153.453094610586;11204.71988200269;11311.321320720783;11320.320780753182;11329.886701132884;11437.913398227156;10193.439360835226;12004.459799308877;12060.396980151132;12201.389930503618;12209.145359825083;7401.629918504161;7606.467547271638;8786.736397908073;8950.015498295312;9000.639974401212;11560.68786242738;11848.940755296167;12141.453634586289;12205.511779529075;12294.81557776652 23:15:35 INFO - PID 1642 | |3;mootools.html;1400.1319182107093;1444.7905053767197;1450.209635748517;1469.6546311616776;1477.2613693153426;11542.192046556744;11702.765944681101;11928.880711192885;11957.282563046223;12022.51909923602;11665.650030499077;11728.12039097069;11846.881531184707;11848.585299514518;11857.11071669624;2484.788792952597;2528.8229823912334;2543.987280063597;2544.7836933860553;2547.643329933811;2464.7412021737728;2491.813114016456;2520.823542352036;2525.2802951158924;2525.6337831014584;2467.383154211447;2504.060977133575;2520.3573088862404;2521.520911026899;2525.2802951158924;2106.115431518766;2487.6019836826113;2496.5506208882393;2502.7997760179233;2503.5368456835545;2428.854268743881;2508.4606809536035;2513.773760361576;2524.5203411351968;2528.190978886757;11738.413079345999;11802.46888890002;11856.288622682667;11943.581974630933;11949.462274197682;6574.572652777554;6775.322467753234;6805.591664500146;6823.147106611674;6854.828629284258;4139.855105071339;4378.759168245745;4447.08834688888;4470.217711900437;4503.977480112611;1261.2747670089643;1272.9363531823371;1295.565985394894;1317.0977880152032;1320.8811206991418;16326.510204693877;16652.916735416246;16687.83312166875;16733;16871.73461990368;5580.720963951786;5668.036433806263;5818.4763371296785;5841.41585841421;5848.415158484203;12304.876951230373;12316.630501084981;12370.876291237153;12373.072019598567;12405.379731013594;6579.80260592183;6588.8023359299295;6671.33286671329;6672.866542669217;6680.699368528428;6056.727447264883;6106.938930610726;6137.232845894263;6228.750849966042;6240.65676387794;4313.568643135724;4441.311596702516;4464.464264288306;4470.485894122153;4498.50516443191;1203.9337836418906;1204.4098391788136;1213.2902252182396;1219.9748417626697;1225.221984040141;791.3313250303485;803.2650125135435;822.2763967708498;825.455199568294;854.2098558833179;5442.673439593637;6006.879862402728;6143.56995010345;6158.753649853956;6304.653244071528;533.8665333666584;584.4652143288928;587.5710731166273;610.4383966750601;615.3938370704852;3443.4490481523335;3716.2567486502267;3808.409696497047;4039.979800100981;4240.660747140221;14756.852431475549;15388.076715397112;15396.615084622974;15400.845991540165;15448.841336899777;14595.197264150574;14748.336324865193;15300.387984480718;15371.38514459409;15385.153816540198;8343.45767525109;8582.87125693115;8691.34814888886;8709.172628600272;8710.303175745925;6958.338957799001;7160.928390716026;7231.746888858865;7262.346388825031;7288.453365997465;13438.731225375243;13658.658533536742;13694.178349298676;13742.65643358924;13843.377048032662;11514.712132196762;13753.037287389787;13868.167909925436;13974.790378144133;14092.647683807987;9614.519274036411;9770;9795.51022448889;9804.65683701067;9833.262505311986;10699.679009629723;10835.404052776857;10942;10947.124230061416;10974.122070234202;9126.361154719107;9201.56036435118;9201.677941272023;9263.722088337361;9390.859137113086;8957.10428957099;9065.546722663972;9103.271738260792;9136.771580710269;9140.405873618194;8123.147069557612;8158.061822890443;8166.142555031874;8219.753407397788;8262.132476090164;6070.817875463743;6089.056196289582;6096.390360963868;6116.633002019893;6189.04069869171;6058.878822423615;6105.392716408422;6173.81478555644;6239.906401403891;6347.841303967438 23:15:35 INFO - PID 1642 | |4;prototype.html;349.96500349965;391.10632205410644;403.6972270796902;416.06800766283516;417.1157146848681;5566.638168519048;5967.956342265449;6170.3212646608945;6338.556301058922;6439.806805795822;5519.779208831642;5609.18575368749;6224.564280500367;6256.217972753406;6284.308726040132;3852.8266228019666;4545.659075569329;4574.748388838612;4610.446746390421;4616.944445831826;4476.283794592866;4586.564276393738;4611.538846115378;4616.238320677085;4627.629789616823;3499.912502187453;4457.977710111461;4526.026904215593;4533.795979180928;4599.425071866017;4188.481675392684;4560.452745670525;4562.201614717428;4581.465209154937;4581.977090114528;3450.631878529912;3459.7232221422228;3856.8264428100524;3939.5469521005166;3940.192260586573;5327.706976116312;6110.236220472441;6111.969440152815;6116.327204007555;6131.202943617346;5396.865078373072;5431.6740995540395;5443.482869127427;5459.918101228484;5467.398586155519;5793.015187418149;6558.672066396709;6619.404253617149;6640.767573134917;6679;4381.846635367779;4398.560143985608;4417.646588272931;4422.756748378838;4436.933445998313;5297;5580.358258800249;6105.694715264219;6862.897056544156;6890.724371025153;3931.68546516278;4380.934285985712;4473;4480.350349199348;4511.842085526991;4571.200039993055;4578;4582.816687332536;4595.1728688835665;4600.332951721982;4760.452547957029;5180.948190518122;5378.848460615785;5406.567474602022;5408.432114627986;5875.648600821835;6677.23211830636;6681.866362672719;6712.060311556386;6712.207602704457;1445.71808497342;1465.5749832548443;1552.6584151486654;1577.5898266450806;1578.8026496687914;992.6873034993954;1001.4692213127055;1026.2303272545591;1040.4225654761528;1042.7018362139033;5342.439043900412;5431.293931788842;5645.379008309083;5829.912551311733;6332.810015699536;6058;6941.895871561931;6954.269801670853;6969.6515174242095;6977.93022069783;5227.921581176285;6229.006717850244;6249.843753906189;6314.778982735582;6317.557770955989;3063.601731774855;3639.29033838399;3641.326354624403;3648.4709717090873;3662.50556174913;6760.594364338155;7014.158301003912;7067.293270672892;7072.823179420453;7093.609851458116;5860.912086318709;6421.1652485177565;6787.133538277013;7147.678354474061;7154.534955227893;5726.885462290731;6744.988251762275;6767.492438067263;7027.945808128821;7052.612106334201;5823.272090988627;5824.388439213991;6253.812385628438;6300.716467759054;6991.580505169706;6162.661053642093;6496.935030649633;6759.831004224934;6828.624425656637;6832.419244364285;7038.859222815413;7047.823804404931;7052.9647351762915;7061.25753350725;7068.3285087916565;4381.802818873222;4512.120136573337;4514.548545145591;4515.480719717275;4530.524294949115;5584.46947539983;6005.939940600538;6898.482613804085;6920.373712177534;6953.4089602384365;6190.380961903774;6633.800985970429;6667.366600172976;6677.465802735866;6696.493289010129;5889.646621202742;6303.810885673253;6623.867522649423;6655.966720166562;6658.300878407697;5341.919871201857;6259.280182778856;6415.332013676384;6590.637514936724;6603.339666033358;6114.877702446015;6485.481161507162;6630.370114839083;6683.365080317362;6766.729330826712;6265.248170219603;6485.448736857231;6550.672466376567;6556.311587283458;6571.34286571339 23:15:35 INFO - PID 1642 | |5;yui.html;830.1034882327087;860.1140824950302;861.5433820075363;870.5386145342968;875.1817051057262;14407.351669174886;14996.850031499682;16809.915950420247;17082.231299591516;17142.82857171428;13147.539836105738;13736.656583585416;17906.64186716265;17929.551761205978;17945.192466339013;982.7150126463335;1023.4729114506035;1026.5944951744057;1030.7732298894232;1031.8452232165198;975.9072888075622;1004.8643433136506;1024.8462730590397;1025.9794804103913;1028.547439126782;1011.9425200665322;1048.8636477257985;1054.620336678795;1071.4160782373624;1078.9352638841694;1039.3867618105278;1041.552132582989;1041.7083216699336;1045.1743122932912;1045.2526443592792;755.9848803023908;775.2054144501931;778;779.3142035009156;779.6667698236023;14998.850011499964;16427.58931026722;17035.744463833053;17206.225719842638;17847.643047139114;16603.252853621616;17073.658526829393;17268.481945541655;17360.479185624452;17412.564685882826;14069.577912662637;14137.293135343192;14169.14985100887;14291.285435728172;14313.069650472686;3262.6737326267184;3435.1068722132177;3493.807840568768;3509.929801403983;3513.2095278562374;101661.933522659;106145.46927265314;114828.42585787017;123094;127054;13481.123726957912;13716.314184290944;13784.310784460737;13826.446942122404;13858.099223550638;22090;25035.499290014097;25707;26105.08632197864;26147.73852261453;7304.926950730531;8014.358851291882;8083.959580202179;8203.138670439637;8226;4021.0148513613767;4034;4113.691473139466;4118.9588104119175;4122.464079669624;4313.288307429302;4435.534268901783;4447.266201076851;4480.798364073625;4498.955010449854;869.3610196505564;899.0515006667897;907.0521305236044;907.3285768531372;907.568904770242;631.207834168116;652.8596351784389;684.1004079635263;684.226823689228;689.1798759476208;10877.728056798644;11879.406029698654;11885.821712674317;12121;12198.207116537396;654.8526581519216;656.7208936202095;657.7796438193163;674.2414783368711;698.2947223304398;4100.528439229467;5129.461406552333;5391.002664507;5637.68992705397;5692.715364231772;98878.50560747294;102210;105663.47168264109;106434.46782766037;108147;91242.08757912468;94596.0540394601;95507.52246238863;97045;97934;58073.41926580764;82264.9999999988;83947;84077.91065072108;84168;10001.1499022581;10823;11094.223404361617;11214.215004949576;11283.323000619988;42506.36240456458;43495.78252108719;43532.12935741331;44824;45145;40332.59667403406;44842.77578612086;55273;55458;56166.31501054975;13934.930325348309;14180.929095354457;14206.715865682421;14214.502492412716;14252.144871307755;15214.011089279162;15383.76924346158;15421.768673469682;15529.844701553291;15689.137097459292;13831.723365532835;13838;13851;13957.3719182635;14020.018598698402;14018.929905350407;14031.789523157366;14110.153390796586;14165.150090994573;14284.785728213878;13820.723585528434;13893.930530347283;13938.442462301395;13972.231527266098;14016.29918504091;10270.845937311096;10349.793004140025;10539.63111291101;10727.785444291227;10768.46157692128;11161.497732602214;11594;11596;11737.237079589799;11877 23:15:35 INFO - PID 1642 | __end_tp_report 23:15:35 INFO - PID 1642 | __start_cc_report 23:15:35 INFO - PID 1642 | _x_x_mozilla_cycle_collect,423 23:15:35 INFO - PID 1642 | __end_cc_report 23:15:35 INFO - PID 1642 | __startTimestamp1492582535446__endTimestamp 23:15:35 INFO - PID 1642 | 23:15:35 INFO - PID 1642 | ------- Summary: start ------- 23:15:35 INFO - PID 1642 | Number of tests: 6 23:15:35 INFO - PID 1642 | 23:15:35 INFO - PID 1642 | [#0] dojo.html Cycles:165 Average:6088.14 Median:6311.53 stddev:774.49 (12.3%) stddev-sans-first:741.44 23:15:35 INFO - PID 1642 | Values: 3136.2 3568.0 3592.9 3597.4 3602.5 4757.4 5798.3 6116.3 6145.7 6250.8 6141.6 6154.8 6169.8 6225.4 6241.4 4261.6 5443.7 5454.3 5457.3 5499.9 5403.3 5424.8 5485.8 5586.5 5597.4 5276.2 5379.4 5437.1 5500.2 5506.5 4439.4 5329.7 5368.5 5451.4 5490.4 5332.2 5369.6 5380.3 5382.1 5397.7 4688.8 6126.7 6181.4 6204.4 6224.9 6403.4 6503.1 6568.4 6675.8 6678.7 6743.8 6757.7 6772.2 6783.9 6799.1 6242.1 6289.5 6301.5 6317.8 6321.9 6632.3 6699.9 6796.5 6825.4 6878.4 6263.2 6398.7 6473.2 6612.7 6658.1 5317.4 6596.4 6720.4 6773.2 6794.9 6663.2 6674.9 6675.6 6678.2 6697.9 5699.0 6350.9 6476.4 6629.7 6707.6 4942.8 5366.0 5381.0 5381.4 5430.3 4077.8 4770.6 4774.2 4784.4 4785.2 6126.2 6171.2 6256.6 6256.9 6306.7 6695.3 6733.6 6773.5 6784.2 6787.9 6212.3 6250.7 6276.0 6289.2 6311.5 4998.9 5719.4 5955.4 5981.5 5994.1 6690.3 6743.8 6798.7 6829.4 6839.1 5759.5 6750.1 6774.2 6778.1 6799.2 6820.1 6837.2 6862.3 6900.4 6900.7 6270.9 6386.3 6496.2 6584.7 6678.1 5647.5 6753.4 6798.5 6818.0 6824.6 6144.0 6377.9 6580.4 6591.1 6595.8 6496.3 6581.3 6599.6 6600.6 6606.8 6193.6 6269.7 6328.5 6389.7 6486.2 5767.5 6577.9 6580.1 6609.7 6611.5 6396.2 6429.8 6442.3 6463.2 6474.2 23:15:35 INFO - PID 1642 | 23:15:35 INFO - PID 1642 | [#1] ext.html Cycles:180 Average:43296.34 Median:35330.41 stddev:73461.34 (207.9%) stddev-sans-first:73597.94 23:15:35 INFO - PID 1642 | Values: 743.0 783.9 818.0 1249.6 1254.2 37468.8 38012.2 38061.4 38085.0 38233.9 38610.4 38668.6 38842.6 38876.8 38976.6 8132.1 8260.6 8553.2 8838.5 8865.0 8634.2 8656.0 8708.3 8729.4 8734.3 8654.9 8745.6 8750.9 8764.8 8778.5 8695.2 8726.9 8736.8 8742.2 8766.2 8495.7 8545.3 8554.5 8572.7 8580.8 38308.8 38380.0 38381.4 38414.6 38475.4 3620.5 3654.3 3655.8 3660.0 3665.6 30377.4 30472.7 30529.0 30551.7 30572.0 3172.5 3216.2 3217.9 3230.3 3233.1 256789.0 294498.0 301438.5 302504.0 302981.0 48533.8 48578.0 48644.3 48675.3 48784.5 41563.6 50085.7 50284.7 51677.7 52023.2 13609.3 13622.5 13706.0 13712.6 13724.7 9408.9 10949.8 10963.3 11053.3 11088.3 13112.0 13132.9 13143.2 13189.9 13387.7 4902.7 5108.9 5915.9 5929.4 5986.5 942.1 946.9 995.5 1015.6 1025.6 23338.2 24052.2 24474.3 24561.5 24656.4 985.7 1011.5 1061.1 1125.7 1170.6 10694.6 10968.5 11099.6 12216.6 12680.0 292690.0 294356.0 296072.0 296366.0 298073.0 43742.8 54475.0 54677.2 54816.0 54975.0 1462.8 1514.8 1558.0 1559.9 1571.6 22127.3 23946.0 23959.2 24103.4 24233.8 38546.0 38724.8 38963.6 39016.0 39069.4 210969.0 261015.0 261571.0 261676.4 261877.0 34550.7 34621.3 34971.0 35689.8 36572.9 44411.8 44936.7 44970.9 45013.0 45022.0 26682.3 28928.4 29181.0 30079.4 31338.2 26932.5 27402.7 28386.3 30948.7 31353.7 27862.7 36590.0 37038.6 37351.4 37362.8 30716.8 30984.8 31485.0 31775.4 31880.0 20106.4 22669.5 23321.1 24477.6 24590.0 23:15:35 INFO - PID 1642 | 23:15:35 INFO - PID 1642 | [#2] jquery.html Cycles:180 Average:36015.63 Median:12083.99 stddev:96854.70 (801.5%) stddev-sans-first:97089.50 23:15:35 INFO - PID 1642 | Values: 407.7 425.4 426.2 443.4 449.3 21023.0 21105.5 21700.9 21976.2 22218.2 16248.7 21608.7 22349.1 22530.9 22640.3 5649.9 5814.1 6183.3 6217.5 6228.8 5253.7 6022.5 6028.6 6103.8 6112.8 6031.1 6060.7 6092.9 6093.9 6124.7 4990.4 5930.3 6047.2 6052.8 6064.8 5238.0 5347.4 5369.8 5376.9 5416.1 26995.3 27161.6 27291.7 27374.0 27638.6 1910.3 2177.5 2210.7 2211.9 2214.1 18233.6 18239.1 18456.6 18463.4 18553.4 1963.3 2180.0 2191.7 2238.6 2239.4 156950.0 159399.0 160307.0 161408.0 162636.7 12535.7 12587.2 12728.4 12853.0 12882.8 16226.4 16451.0 16476.8 16485.7 16488.6 8602.9 8762.7 8916.5 9018.7 9034.4 8369.5 8375.7 8417.2 8583.4 8617.0 7883.8 7977.8 7989.1 8085.0 8122.4 2141.0 2164.8 2166.2 2169.3 2174.3 962.0 973.7 984.4 1023.1 1026.6 15941.2 17582.1 19815.0 20177.1 20936.1 1001.8 1011.9 1032.7 1043.6 1044.8 7793.7 10279.5 10428.4 10817.3 10877.6 538919.0 540062.3 588601.0 596093.0 597091.0 103241.0 119948.0 124791.0 128759.1 131048.0 1066.0 1106.3 1203.0 1203.9 1260.2 14674.9 18385.9 21257.3 21259.5 21267.3 53426.0 54925.5 56462.7 56988.1 57327.7 70940.6 76838.0 81265.6 82164.0 83294.0 7138.8 8852.4 8858.0 8976.9 8996.5 12750.2 13113.0 13936.2 14269.1 14403.9 11539.6 11615.6 12050.7 12127.9 12153.5 11204.7 11311.3 11320.3 11329.9 11437.9 10193.4 12004.5 12060.4 12201.4 12209.1 7401.6 7606.5 8786.7 8950.0 9000.6 11560.7 11848.9 12141.5 12205.5 12294.8 23:15:35 INFO - PID 1642 | 23:15:35 INFO - PID 1642 | [#3] mootools.html Cycles:180 Average:7194.86 Median:3579.85 stddev:4588.73 (128.2%) stddev-sans-first:4580.94 23:15:35 INFO - PID 1642 | Values: 1400.1 1444.8 1450.2 1469.7 1477.3 11542.2 11702.8 11928.9 11957.3 12022.5 11665.7 11728.1 11846.9 11848.6 11857.1 2484.8 2528.8 2544.0 2544.8 2547.6 2464.7 2491.8 2520.8 2525.3 2525.6 2467.4 2504.1 2520.4 2521.5 2525.3 2106.1 2487.6 2496.6 2502.8 2503.5 2428.9 2508.5 2513.8 2524.5 2528.2 11738.4 11802.5 11856.3 11943.6 11949.5 6574.6 6775.3 6805.6 6823.1 6854.8 4139.9 4378.8 4447.1 4470.2 4504.0 1261.3 1272.9 1295.6 1317.1 1320.9 16326.5 16652.9 16687.8 16733.0 16871.7 5580.7 5668.0 5818.5 5841.4 5848.4 12304.9 12316.6 12370.9 12373.1 12405.4 6579.8 6588.8 6671.3 6672.9 6680.7 6056.7 6106.9 6137.2 6228.8 6240.7 4313.6 4441.3 4464.5 4470.5 4498.5 1203.9 1204.4 1213.3 1220.0 1225.2 791.3 803.3 822.3 825.5 854.2 5442.7 6006.9 6143.6 6158.8 6304.7 533.9 584.5 587.6 610.4 615.4 3443.4 3716.3 3808.4 4040.0 4240.7 14756.9 15388.1 15396.6 15400.8 15448.8 14595.2 14748.3 15300.4 15371.4 15385.2 8343.5 8582.9 8691.3 8709.2 8710.3 6958.3 7160.9 7231.7 7262.3 7288.5 13438.7 13658.7 13694.2 13742.7 13843.4 11514.7 13753.0 13868.2 13974.8 14092.6 9614.5 9770.0 9795.5 9804.7 9833.3 10699.7 10835.4 10942.0 10947.1 10974.1 9126.4 9201.6 9201.7 9263.7 9390.9 8957.1 9065.5 9103.3 9136.8 9140.4 8123.1 8158.1 8166.1 8219.8 8262.1 6070.8 6089.1 6096.4 6116.6 6189.0 6058.9 6105.4 6173.8 6239.9 6347.8 23:15:35 INFO - PID 1642 | 23:15:35 INFO - PID 1642 | [#4] prototype.html Cycles:180 Average:5313.19 Median:5845.41 stddev:1669.54 (28.6%) stddev-sans-first:1632.13 23:15:35 INFO - PID 1642 | Values: 350.0 391.1 403.7 416.1 417.1 5566.6 5968.0 6170.3 6338.6 6439.8 5519.8 5609.2 6224.6 6256.2 6284.3 3852.8 4545.7 4574.7 4610.4 4616.9 4476.3 4586.6 4611.5 4616.2 4627.6 3499.9 4458.0 4526.0 4533.8 4599.4 4188.5 4560.5 4562.2 4581.5 4582.0 3450.6 3459.7 3856.8 3939.5 3940.2 5327.7 6110.2 6112.0 6116.3 6131.2 5396.9 5431.7 5443.5 5459.9 5467.4 5793.0 6558.7 6619.4 6640.8 6679.0 4381.8 4398.6 4417.6 4422.8 4436.9 5297.0 5580.4 6105.7 6862.9 6890.7 3931.7 4380.9 4473.0 4480.4 4511.8 4571.2 4578.0 4582.8 4595.2 4600.3 4760.5 5180.9 5378.8 5406.6 5408.4 5875.6 6677.2 6681.9 6712.1 6712.2 1445.7 1465.6 1552.7 1577.6 1578.8 992.7 1001.5 1026.2 1040.4 1042.7 5342.4 5431.3 5645.4 5829.9 6332.8 6058.0 6941.9 6954.3 6969.7 6977.9 5227.9 6229.0 6249.8 6314.8 6317.6 3063.6 3639.3 3641.3 3648.5 3662.5 6760.6 7014.2 7067.3 7072.8 7093.6 5860.9 6421.2 6787.1 7147.7 7154.5 5726.9 6745.0 6767.5 7027.9 7052.6 5823.3 5824.4 6253.8 6300.7 6991.6 6162.7 6496.9 6759.8 6828.6 6832.4 7038.9 7047.8 7053.0 7061.3 7068.3 4381.8 4512.1 4514.5 4515.5 4530.5 5584.5 6005.9 6898.5 6920.4 6953.4 6190.4 6633.8 6667.4 6677.5 6696.5 5889.6 6303.8 6623.9 6656.0 6658.3 5341.9 6259.3 6415.3 6590.6 6603.3 6114.9 6485.5 6630.4 6683.4 6766.7 6265.2 6485.4 6550.7 6556.3 6571.3 23:15:35 INFO - PID 1642 | 23:15:35 INFO - PID 1642 | [#5] yui.html Cycles:180 Average:20983.10 Median:17054.70 stddev:29852.18 (175.0%) stddev-sans-first:29897.57 23:15:35 INFO - PID 1642 | Values: 830.1 860.1 861.5 870.5 875.2 14407.4 14996.9 16809.9 17082.2 17142.8 13147.5 13736.7 17906.6 17929.6 17945.2 982.7 1023.5 1026.6 1030.8 1031.8 975.9 1004.9 1024.8 1026.0 1028.5 1011.9 1048.9 1054.6 1071.4 1078.9 1039.4 1041.6 1041.7 1045.2 1045.3 756.0 775.2 778.0 779.3 779.7 14998.9 16427.6 17035.7 17206.2 17847.6 16603.3 17073.7 17268.5 17360.5 17412.6 14069.6 14137.3 14169.1 14291.3 14313.1 3262.7 3435.1 3493.8 3509.9 3513.2 101661.9 106145.5 114828.4 123094.0 127054.0 13481.1 13716.3 13784.3 13826.4 13858.1 22090.0 25035.5 25707.0 26105.1 26147.7 7304.9 8014.4 8084.0 8203.1 8226.0 4021.0 4034.0 4113.7 4119.0 4122.5 4313.3 4435.5 4447.3 4480.8 4499.0 869.4 899.1 907.1 907.3 907.6 631.2 652.9 684.1 684.2 689.2 10877.7 11879.4 11885.8 12121.0 12198.2 654.9 656.7 657.8 674.2 698.3 4100.5 5129.5 5391.0 5637.7 5692.7 98878.5 102210.0 105663.5 106434.5 108147.0 91242.1 94596.1 95507.5 97045.0 97934.0 58073.4 82265.0 83947.0 84077.9 84168.0 10001.1 10823.0 11094.2 11214.2 11283.3 42506.4 43495.8 43532.1 44824.0 45145.0 40332.6 44842.8 55273.0 55458.0 56166.3 13934.9 14180.9 14206.7 14214.5 14252.1 15214.0 15383.8 15421.8 15529.8 15689.1 13831.7 13838.0 13851.0 13957.4 14020.0 14018.9 14031.8 14110.2 14165.2 14284.8 13820.7 13893.9 13938.4 13972.2 14016.3 10270.8 10349.8 10539.6 10727.8 10768.5 11161.5 11594.0 11596.0 11737.2 11877.0 23:15:35 INFO - PID 1642 | -------- Summary: end -------- 23:15:35 INFO - PID 1642 | 23:15:36 INFO - TEST-INFO | 1642: exit 0 23:15:36 INFO - TEST-OK | dromaeo_css | took 1115937ms 23:15:36 INFO - TEST-START | kraken 23:15:36 INFO - operating with platform_type : mac_ 23:15:36 INFO - Initialising browser for kraken test... 23:15:36 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmp_lAL63/profile http://localhost:49240/getInfo.html) 23:15:39 INFO - PID 1656 | 2017-04-18 23:15:39.060 plugin-container[1657:10222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8833, name = 'com.apple.tsm.portname' 23:15:39 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:15:39 INFO - PID 1656 | 2017-04-18 23:15:39.062 plugin-container[1657:10222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 23:15:39 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:15:39 INFO - PID 1656 | 2017-04-18 23:15:39.062 plugin-container[1657:10222] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:15:39 INFO - PID 1656 | __metrics Screen width/height:1600/1200 23:15:39 INFO - PID 1656 | colorDepth:24 23:15:39 INFO - PID 1656 | Browser inner width/height: 1024/689 23:15:39 INFO - PID 1656 | __metrics 23:15:39 ERROR - PID 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3385: TypeError: this.tabs is undefined 23:15:40 INFO - TEST-INFO | 1656: exit 0 23:15:40 INFO - Browser initialized. 23:15:40 INFO - Running cycle 1/1 for kraken test... 23:15:40 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmp_lAL63/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:15:42 INFO - PID 1658 | 2017-04-18 23:15:42.456 plugin-container[1659:10354] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 23:15:42 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:15:42 INFO - PID 1658 | 2017-04-18 23:15:42.457 plugin-container[1659:10354] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.CFPasteboardClient' 23:15:42 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:15:42 INFO - PID 1658 | 2017-04-18 23:15:42.457 plugin-container[1659:10354] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:15:52 INFO - PID 1658 | RSS: Main: 236236800 23:15:52 INFO - PID 1658 | 23:15:55 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 23:15:56 INFO - PID 1658 | RSS: Main: 249344000 23:15:56 INFO - PID 1658 | 23:15:58 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 23:15:59 INFO - PID 1658 | RSS: Main: 257740800 23:15:59 INFO - PID 1658 | 23:16:02 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 23:16:03 INFO - PID 1658 | RSS: Main: 260489216 23:16:03 INFO - PID 1658 | 23:16:05 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 23:16:05 INFO - PID 1658 | RSS: Main: 262361088 23:16:05 INFO - PID 1658 | 23:16:07 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:16:08 INFO - PID 1658 | RSS: Main: 262860800 23:16:08 INFO - PID 1658 | 23:16:11 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 23:16:12 INFO - PID 1658 | RSS: Main: 268091392 23:16:12 INFO - PID 1658 | 23:16:15 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 23:16:15 INFO - PID 1658 | RSS: Main: 270118912 23:16:15 INFO - PID 1658 | 23:16:18 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 23:16:19 INFO - PID 1658 | RSS: Main: 269717504 23:16:19 INFO - PID 1658 | 23:16:21 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:16:21 INFO - PID 1658 | RSS: Main: 269381632 23:16:21 INFO - PID 1658 | 23:16:23 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:16:24 INFO - PID 1658 | RSS: Main: 269418496 23:16:24 INFO - PID 1658 | 23:16:26 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:16:27 INFO - PID 1658 | RSS: Main: 268984320 23:16:27 INFO - PID 1658 | 23:16:29 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:16:30 INFO - PID 1658 | RSS: Main: 269144064 23:16:30 INFO - PID 1658 | 23:16:33 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:16:34 INFO - PID 1658 | RSS: Main: 269283328 23:16:34 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 23:16:36 INFO - PID 1658 | RSS: Main: 270995456 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | __start_tp_report 23:16:36 INFO - PID 1658 | _x_x_mozilla_page_load 23:16:36 INFO - PID 1658 | _x_x_mozilla_page_load_details 23:16:36 INFO - PID 1658 | |i|pagename|runs| 23:16:36 INFO - PID 1658 | |0;ai-astar;104;114;117;116;117;115;113;114;115;118 23:16:36 INFO - PID 1658 | |1;audio-beat-detection;139;112;108;148;110;111;110;109;153;98 23:16:36 INFO - PID 1658 | |2;audio-dft;199;198;195;208;252;249;199;199;197;200 23:16:36 INFO - PID 1658 | |3;audio-fft;85;61;60;82;59;60;59;63;79;61 23:16:36 INFO - PID 1658 | |4;audio-oscillator;71;64;92;99;98;101;107;72;70;88 23:16:36 INFO - PID 1658 | |5;imaging-gaussian-blur;89;89;91;88;92;88;88;89;89;82 23:16:36 INFO - PID 1658 | |6;imaging-darkroom;96;97;99;96;124;102;150;138;96;149 23:16:36 INFO - PID 1658 | |7;imaging-desaturate;91;102;143;93;92;96;94;141;94;144 23:16:36 INFO - PID 1658 | |8;json-parse-financial;51;51;50;51;51;51;53;51;53;52 23:16:36 INFO - PID 1658 | |9;json-stringify-tinderbox;56;65;62;67;86;88;82;78;59;58 23:16:36 INFO - PID 1658 | |10;stanford-crypto-aes;83;81;83;80;84;81;85;86;84;85 23:16:36 INFO - PID 1658 | |11;stanford-crypto-ccm;140;140;147;148;143;155;153;143;231;133 23:16:36 INFO - PID 1658 | |12;stanford-crypto-pbkdf2;187;187;185;188;185;192;182;184;186;199 23:16:36 INFO - PID 1658 | |13;stanford-crypto-sha256-iterative;69;67;70;76;89;90;92;71;69;71 23:16:36 INFO - PID 1658 | __end_tp_report 23:16:36 INFO - PID 1658 | __start_cc_report 23:16:36 INFO - PID 1658 | _x_x_mozilla_cycle_collect,1012 23:16:36 INFO - PID 1658 | __end_cc_report 23:16:36 INFO - PID 1658 | __startTimestamp1492582596489__endTimestamp 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | ------- Summary: start ------- 23:16:36 INFO - PID 1658 | Number of tests: 14 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.50 stddev:3.95 (3.4%) stddev-sans-first:1.67 23:16:36 INFO - PID 1658 | Values: 104.0 114.0 117.0 116.0 117.0 115.0 113.0 114.0 115.0 118.0 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | [#1] audio-beat-detection Cycles:10 Average:119.80 Median:125.50 stddev:19.23 (15.3%) stddev-sans-first:19.10 23:16:36 INFO - PID 1658 | Values: 139.0 112.0 108.0 148.0 110.0 111.0 110.0 109.0 153.0 98.0 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | [#2] audio-dft Cycles:10 Average:209.60 Median:199.50 stddev:21.83 (10.9%) stddev-sans-first:22.81 23:16:36 INFO - PID 1658 | Values: 199.0 198.0 195.0 208.0 252.0 249.0 199.0 199.0 197.0 200.0 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | [#3] audio-fft Cycles:10 Average:66.90 Median:62.00 stddev:10.58 (17.1%) stddev-sans-first:8.96 23:16:36 INFO - PID 1658 | Values: 85.0 61.0 60.0 82.0 59.0 60.0 59.0 63.0 79.0 61.0 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | [#4] audio-oscillator Cycles:10 Average:86.20 Median:80.00 stddev:15.56 (19.5%) stddev-sans-first:15.50 23:16:36 INFO - PID 1658 | Values: 71.0 64.0 92.0 99.0 98.0 101.0 107.0 72.0 70.0 88.0 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:89.00 stddev:2.64 (3.0%) stddev-sans-first:2.79 23:16:36 INFO - PID 1658 | Values: 89.0 89.0 91.0 88.0 92.0 88.0 88.0 89.0 89.0 82.0 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | [#6] imaging-darkroom Cycles:10 Average:114.70 Median:96.00 stddev:23.15 (24.1%) stddev-sans-first:23.54 23:16:36 INFO - PID 1658 | Values: 96.0 97.0 99.0 96.0 124.0 102.0 150.0 138.0 96.0 149.0 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | [#7] imaging-desaturate Cycles:10 Average:109.00 Median:92.50 stddev:23.43 (25.3%) stddev-sans-first:23.93 23:16:36 INFO - PID 1658 | Values: 91.0 102.0 143.0 93.0 92.0 96.0 94.0 141.0 94.0 144.0 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:51.00 stddev:0.97 (1.9%) stddev-sans-first:1.01 23:16:36 INFO - PID 1658 | Values: 51.0 51.0 50.0 51.0 51.0 51.0 53.0 51.0 53.0 52.0 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:72.50 stddev:12.23 (16.9%) stddev-sans-first:11.86 23:16:36 INFO - PID 1658 | Values: 56.0 65.0 62.0 67.0 86.0 88.0 82.0 78.0 59.0 58.0 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:84.00 stddev:1.99 (2.4%) stddev-sans-first:2.11 23:16:36 INFO - PID 1658 | Values: 83.0 81.0 83.0 80.0 84.0 81.0 85.0 86.0 84.0 85.0 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:153.30 Median:147.50 stddev:28.06 (19.0%) stddev-sans-first:29.35 23:16:36 INFO - PID 1658 | Values: 140.0 140.0 147.0 148.0 143.0 155.0 153.0 143.0 231.0 133.0 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.50 Median:187.00 stddev:4.84 (2.6%) stddev-sans-first:5.13 23:16:36 INFO - PID 1658 | Values: 187.0 187.0 185.0 188.0 185.0 192.0 182.0 184.0 186.0 199.0 23:16:36 INFO - PID 1658 | 23:16:36 INFO - PID 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.40 Median:73.50 stddev:9.91 (13.5%) stddev-sans-first:10.15 23:16:36 INFO - PID 1658 | Values: 69.0 67.0 70.0 76.0 89.0 90.0 92.0 71.0 69.0 71.0 23:16:36 INFO - PID 1658 | -------- Summary: end -------- 23:16:36 INFO - PID 1658 | 23:16:37 INFO - TEST-INFO | 1658: exit 0 23:16:37 INFO - TEST-OK | kraken | took 61473ms 23:16:37 INFO - SUITE-END | took 1177s 23:16:37 INFO - Completed test suite (00:19:37) 23:16:37 INFO - javascript benchmark 23:16:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7802.40878509337, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3136.200268931422, 3567.965290065881, 3592.91017724557, 3597.424412094065, 3602.513660655811, 4757.357756702844, 5798.3042034955815, 6116.327204007555, 6145.72344244509, 6250.843728906774, 6141.631502109876, 6154.769046190758, 6169.814905552829, 6225.377462253784, 6241.407066328703, 4261.573842615745, 5443.727813609334, 5454.34547854258, 5457.317835270591, 5499.945000549983, 5403.297571315724, 5424.783008679648, 5485.780568777244, 5586.469285417878, 5597.412271711472, 5276.208568714724, 5379.435159308256, 5437.13005919051, 5500.22996780451, 5506.531944784678, 4439.4228750262255, 5329.706866122362, 5368.543673787733, 5451.37309209442, 5490.423505531901, 5332.200169974496, 5369.597280203961, 5380.300560927094, 5382.111951527993, 5397.68042653769, 4688.780916961615, 6126.744017476407, 6181.3818618138275, 6204.410580994799, 6224.937750622481, 6403.391677790602, 6503.057056726795, 6568.408843204134, 6675.7997260082275, 6678.732850686015, 6743.831404214885, 6757.729690812411, 6772.187337519529, 6783.864322713518, 6799.082123913257, 6242.094896240111, 6289.528285378615, 6301.52738544611, 6317.842053948596, 6321.905171422386, 6632.303608121078, 6699.933000670028, 6796.52424330302, 6825.419839313714, 6878.38094571491, 6263.154474146023, 6398.712057957403, 6473.190851143607, 6612.7354905804195, 6658.101156343929, 5317.441668624816, 6596.406323430914, 6720.395164435225, 6773.153355830521, 6794.864102717918, 6663.167104111986, 6674.899876501856, 6675.632840193739, 6678.165229346332, 6697.8995315070315, 5699.0, 6350.872982540416, 6476.449501792306, 6629.734810607618, 6707.564008339442, 4942.752862356868, 5366.0, 5381.0, 5381.3811411687375, 5430.348358197041, 4077.7757223352405, 4770.61835053195, 4774.164521208774, 4784.425868895685, 4785.210440277385, 6126.23422072241, 6171.197744293303, 6256.562040657202, 6256.874862502816, 6306.747730090745, 6695.330466953265, 6733.595984240863, 6773.525853190229, 6784.151981002375, 6787.898181527281, 6212.316645169028, 6250.6874656267, 6276.027215781562, 6289.150964619717, 6311.526635502356, 4998.850034498971, 5719.370869204384, 5955.434233747787, 5981.457880400899, 5994.100884867305, 6690.264070952094, 6743.797686069426, 6798.660066996729, 6829.419499342612, 6839.110915580943, 5759.539236860957, 6750.088738020305, 6774.187097548326, 6778.084958530535, 6799.150106236721, 6820.079289295882, 6837.247902730795, 6862.3137686230975, 6900.413464855544, 6900.688869114852, 6270.9372906270355, 6386.329435409401, 6496.155499784998, 6584.670766461753, 6678.098456708282, 5647.463490968352, 6753.3921947025, 6798.456123510206, 6818.0, 6824.590524568542, 6143.972489078683, 6377.904331434939, 6580.407763301326, 6591.077249185022, 6595.769148079794, 6496.317886622026, 6581.3418658135715, 6599.60402375859, 6600.60396376219, 6606.768763093269, 6193.628382296896, 6269.717862696099, 6328.525360597881, 6389.7124629390855, 6486.189226346707, 5767.452092051249, 6577.934220657732, 6580.078788969644, 6609.702563384563, 6611.504137189634, 6396.232452105591, 6429.839254018687, 6442.2591401989375, 6463.15978922737, 6474.1907261592305], "value": 6037.197268192071, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.0, 783.902012248469, 817.9959100204496, 1249.5688987299384, 1254.1973137192192, 37468.812655936716, 38012.23975520488, 38061.429078563844, 38085.04787380317, 38233.85298441048, 38610.420843687294, 38668.61331386685, 38842.61157388425, 38876.80561597188, 38976.610233897656, 8132.064812546559, 8260.628271727757, 8553.230209281164, 8838.513881736502, 8865.0, 8634.222919937205, 8656.047834738174, 8708.346873984476, 8729.432586881863, 8734.301255899512, 8654.87017694735, 8745.562721863913, 8750.912490875073, 8764.824703505927, 8778.473291602524, 8695.21743043126, 8726.86550748405, 8736.781580460476, 8742.213200811926, 8766.167214114648, 8495.702650407206, 8545.31637469001, 8554.52950087745, 8572.657093716243, 8580.785480362978, 38308.80845595782, 38380.0, 38381.424278635845, 38414.615853841664, 38475.42286865699, 3620.529331186955, 3654.26914617075, 3655.780653160819, 3660.0483874192637, 3665.596784353719, 30377.392452151053, 30472.695273047208, 30529.0, 30551.694483055107, 30572.0, 3172.476541370671, 3216.2281052547455, 3217.9034628961167, 3230.305484320859, 3233.0785726067957, 256789.0, 294498.0, 301438.4928075346, 302504.0, 302981.0, 48533.7866553339, 48578.0, 48644.27033594428, 48675.26987095196, 48784.51215487871, 41563.58436415597, 50085.749571252636, 50284.742881427526, 51677.741611291705, 52023.21965170526, 13609.31953402326, 13622.455101795817, 13706.0, 13712.588622341145, 13724.65688357799, 9408.858867116998, 10949.83575246372, 10963.342199467897, 11053.336799792038, 11088.33469991787, 13111.951043916655, 13132.868671313356, 13143.211407315592, 13189.868101319056, 13387.732245355039, 4902.651770763068, 5108.948910510921, 5915.911261331084, 5929.436703513159, 5986.49114825236, 942.0814705661925, 946.8863736351682, 995.5370752599931, 1015.5937624950079, 1025.5590096258532, 23338.183163589194, 24052.15817446381, 24474.26577202687, 24561.508769824504, 24656.383590410383, 985.7486340983004, 1011.5469985285653, 1061.0662616897234, 1125.6904351303458, 1170.5610396101463, 10694.625688101034, 10968.451577421098, 11099.556017759362, 12216.633500994983, 12680.0, 292690.0, 294356.0, 296072.0, 296366.0, 298073.0, 43742.78128609336, 54475.0, 54677.179842302394, 54816.0, 54975.0, 1462.795208670787, 1514.780356848251, 1557.9610509737347, 1559.9454019109276, 1571.5599632103028, 22127.33617991463, 23946.0, 23959.16142934989, 24103.397415064763, 24233.757662423854, 38546.0, 38724.80637596794, 38963.610363896, 39016.0, 39069.41395879007, 210969.0, 261015.0, 261571.0, 261676.3832361652, 261877.0, 34550.65449345474, 34621.30757384788, 34971.0, 35689.82155089208, 36572.90281291566, 44411.777941110086, 44936.65190044304, 44970.87572810706, 45013.0, 45022.0, 26682.332941676614, 28928.359563108625, 29181.0, 30079.398412032075, 31338.21654458657, 26932.46135077327, 27402.725972740816, 28386.290342741595, 30948.69051309548, 31353.686463135076, 27862.721372786822, 36590.0, 37038.62961370352, 37351.43972840464, 37362.8131859339, 30716.846415768672, 30984.760609575365, 31485.0, 31775.364492709556, 31880.0, 20106.396808095782, 22669.546609068057, 23321.067157314195, 24477.632835507124, 24590.0], "value": 17330.949292243633, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.70237726459686, 425.3768229543718, 426.1987463568491, 443.3504915299086, 449.3439578215805, 21023.0, 21105.47236319093, 21700.89149554252, 21976.230831920886, 22218.222362217326, 16248.675026499463, 21608.675869861963, 22349.100326472268, 22530.85956834098, 22640.320790376274, 5649.858753531174, 5814.069748840187, 6183.319834818167, 6217.502599792006, 6228.781992630259, 5253.7373131343475, 6022.488088512459, 6028.577999540034, 6103.786367477139, 6112.847178820521, 6031.125486804411, 6060.666663333515, 6092.908606370908, 6093.908591371133, 6124.724387402578, 4990.351254336932, 5930.258717660293, 6047.183630209954, 6052.848678783065, 6064.757409703607, 5238.0, 5347.438518955531, 5369.838904832861, 5376.865578360573, 5416.1063424534905, 26995.325116872038, 27161.592576111376, 27291.727082729118, 27374.0, 27638.618069096465, 1910.2645481489587, 2177.4665207024223, 2210.7347118345742, 2211.9336419907427, 2214.070090561968, 18233.635327293512, 18239.084896085853, 18456.615753818383, 18463.446096617125, 18553.44339669812, 1963.2539634938632, 2179.9972012873936, 2191.747948985871, 2238.5746708125566, 2239.384169353441, 156950.0, 159399.0, 160307.0, 161408.0, 162636.74726505403, 12535.686607834878, 12587.181833180814, 12728.427220775087, 12853.0, 12882.80675789864, 16226.350945962266, 16451.0, 16476.83523164777, 16485.670286594443, 16488.587785305463, 8602.87095693565, 8762.737117886474, 8916.465012099296, 9018.729438116867, 9034.412763170372, 8369.539675317794, 8375.74872753806, 8417.200365965224, 8583.442076265022, 8616.956915215509, 7883.802904927423, 7977.840443191104, 7989.121196668362, 8084.959575202086, 8122.431429799975, 2141.0, 2164.7835216478543, 2166.1876796201423, 2169.316665250441, 2174.3476956912864, 962.0091305954878, 973.7341456107033, 984.4487087231173, 1023.0587859169582, 1026.6201505443034, 15941.202939852961, 17582.120893955253, 19815.0, 20177.120150163002, 20936.057877395553, 1001.8196724589498, 1011.9038691324313, 1032.7418145463635, 1043.5512729526224, 1044.8215470743432, 7793.727219547289, 10279.486025698834, 10428.374297542172, 10817.296875703054, 10877.619283325204, 538919.0, 540062.2996885069, 588601.0, 596093.0, 597091.0, 103241.0, 119948.0, 124791.0, 128759.06861397087, 131047.9999999981, 1065.9733506662396, 1106.2753896198003, 1203.0, 1203.9458224379923, 1260.2249616485794, 14674.853251467348, 18385.908070460096, 21257.255996040065, 21259.46851328729, 21267.255646052316, 53426.0, 54925.45074549203, 56462.717686411306, 56988.145177821534, 57327.71336143293, 70940.64529677319, 76838.0, 81265.59367203363, 82164.0, 83294.0, 7138.750143744944, 8852.3803333766, 8857.955710221666, 8976.910230897869, 8996.460212387277, 12750.171238869443, 13113.0, 13936.233507156798, 14269.072510286798, 14403.927980360031, 11539.596114135966, 11615.593454229062, 12050.698732531757, 12127.939360303142, 12153.453094610586, 11204.71988200269, 11311.321320720783, 11320.320780753182, 11329.886701132884, 11437.913398227156, 10193.439360835226, 12004.459799308877, 12060.396980151132, 12201.389930503618, 12209.145359825083, 7401.629918504161, 7606.467547271638, 8786.736397908073, 8950.015498295312, 9000.639974401212, 11560.68786242738, 11848.940755296167, 12141.453634586289, 12205.511779529075, 12294.81557776652], "value": 10341.522351782109, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.1319182107093, 1444.7905053767197, 1450.209635748517, 1469.6546311616776, 1477.2613693153426, 11542.192046556744, 11702.765944681101, 11928.880711192885, 11957.282563046223, 12022.51909923602, 11665.650030499077, 11728.12039097069, 11846.881531184707, 11848.585299514518, 11857.11071669624, 2484.788792952597, 2528.8229823912334, 2543.987280063597, 2544.7836933860553, 2547.643329933811, 2464.7412021737728, 2491.813114016456, 2520.823542352036, 2525.2802951158924, 2525.6337831014584, 2467.383154211447, 2504.060977133575, 2520.3573088862404, 2521.520911026899, 2525.2802951158924, 2106.115431518766, 2487.6019836826113, 2496.5506208882393, 2502.7997760179233, 2503.5368456835545, 2428.854268743881, 2508.4606809536035, 2513.773760361576, 2524.5203411351968, 2528.190978886757, 11738.413079345999, 11802.46888890002, 11856.288622682667, 11943.581974630933, 11949.462274197682, 6574.572652777554, 6775.322467753234, 6805.591664500146, 6823.147106611674, 6854.828629284258, 4139.855105071339, 4378.759168245745, 4447.08834688888, 4470.217711900437, 4503.977480112611, 1261.2747670089643, 1272.9363531823371, 1295.565985394894, 1317.0977880152032, 1320.8811206991418, 16326.510204693877, 16652.916735416246, 16687.83312166875, 16733.0, 16871.73461990368, 5580.720963951786, 5668.036433806263, 5818.4763371296785, 5841.41585841421, 5848.415158484203, 12304.876951230373, 12316.630501084981, 12370.876291237153, 12373.072019598567, 12405.379731013594, 6579.80260592183, 6588.8023359299295, 6671.33286671329, 6672.866542669217, 6680.699368528428, 6056.727447264883, 6106.938930610726, 6137.232845894263, 6228.750849966042, 6240.65676387794, 4313.568643135724, 4441.311596702516, 4464.464264288306, 4470.485894122153, 4498.50516443191, 1203.9337836418906, 1204.4098391788136, 1213.2902252182396, 1219.9748417626697, 1225.221984040141, 791.3313250303485, 803.2650125135435, 822.2763967708498, 825.455199568294, 854.2098558833179, 5442.673439593637, 6006.879862402728, 6143.56995010345, 6158.753649853956, 6304.653244071528, 533.8665333666584, 584.4652143288928, 587.5710731166273, 610.4383966750601, 615.3938370704852, 3443.4490481523335, 3716.2567486502267, 3808.409696497047, 4039.979800100981, 4240.660747140221, 14756.852431475549, 15388.076715397112, 15396.615084622974, 15400.845991540165, 15448.841336899777, 14595.197264150574, 14748.336324865193, 15300.387984480718, 15371.38514459409, 15385.153816540198, 8343.45767525109, 8582.87125693115, 8691.34814888886, 8709.172628600272, 8710.303175745925, 6958.338957799001, 7160.928390716026, 7231.746888858865, 7262.346388825031, 7288.453365997465, 13438.731225375243, 13658.658533536742, 13694.178349298676, 13742.65643358924, 13843.377048032662, 11514.712132196762, 13753.037287389787, 13868.167909925436, 13974.790378144133, 14092.647683807987, 9614.519274036411, 9770.0, 9795.51022448889, 9804.65683701067, 9833.262505311986, 10699.679009629723, 10835.404052776857, 10942.0, 10947.124230061416, 10974.122070234202, 9126.361154719107, 9201.56036435118, 9201.677941272023, 9263.722088337361, 9390.859137113086, 8957.10428957099, 9065.546722663972, 9103.271738260792, 9136.771580710269, 9140.405873618194, 8123.147069557612, 8158.061822890443, 8166.142555031874, 8219.753407397788, 8262.132476090164, 6070.817875463743, 6089.056196289582, 6096.390360963868, 6116.633002019893, 6189.04069869171, 6058.878822423615, 6105.392716408422, 6173.81478555644, 6239.906401403891, 6347.841303967438], "value": 5366.346848021807, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.96500349965, 391.10632205410644, 403.6972270796902, 416.06800766283516, 417.1157146848681, 5566.638168519048, 5967.956342265449, 6170.3212646608945, 6338.556301058922, 6439.806805795822, 5519.779208831642, 5609.18575368749, 6224.564280500367, 6256.217972753406, 6284.308726040132, 3852.8266228019666, 4545.659075569329, 4574.748388838612, 4610.446746390421, 4616.944445831826, 4476.283794592866, 4586.564276393738, 4611.538846115378, 4616.238320677085, 4627.629789616823, 3499.912502187453, 4457.977710111461, 4526.026904215593, 4533.795979180928, 4599.425071866017, 4188.481675392684, 4560.452745670525, 4562.201614717428, 4581.465209154937, 4581.977090114528, 3450.631878529912, 3459.7232221422228, 3856.8264428100524, 3939.5469521005166, 3940.192260586573, 5327.706976116312, 6110.236220472441, 6111.969440152815, 6116.327204007555, 6131.202943617346, 5396.865078373072, 5431.6740995540395, 5443.482869127427, 5459.918101228484, 5467.398586155519, 5793.015187418149, 6558.672066396709, 6619.404253617149, 6640.767573134917, 6679.0, 4381.846635367779, 4398.560143985608, 4417.646588272931, 4422.756748378838, 4436.933445998313, 5297.0, 5580.358258800249, 6105.694715264219, 6862.897056544156, 6890.724371025153, 3931.68546516278, 4380.934285985712, 4473.0, 4480.350349199348, 4511.842085526991, 4571.200039993055, 4578.0, 4582.816687332536, 4595.1728688835665, 4600.332951721982, 4760.452547957029, 5180.948190518122, 5378.848460615785, 5406.567474602022, 5408.432114627986, 5875.648600821835, 6677.23211830636, 6681.866362672719, 6712.060311556386, 6712.207602704457, 1445.71808497342, 1465.5749832548443, 1552.6584151486654, 1577.5898266450806, 1578.8026496687914, 992.6873034993954, 1001.4692213127055, 1026.2303272545591, 1040.4225654761528, 1042.7018362139033, 5342.439043900412, 5431.293931788842, 5645.379008309083, 5829.912551311733, 6332.810015699536, 6058.0, 6941.895871561931, 6954.269801670853, 6969.6515174242095, 6977.93022069783, 5227.921581176285, 6229.006717850244, 6249.843753906189, 6314.778982735582, 6317.557770955989, 3063.601731774855, 3639.29033838399, 3641.326354624403, 3648.4709717090873, 3662.50556174913, 6760.594364338155, 7014.158301003912, 7067.293270672892, 7072.823179420453, 7093.609851458116, 5860.912086318709, 6421.1652485177565, 6787.133538277013, 7147.678354474061, 7154.534955227893, 5726.885462290731, 6744.988251762275, 6767.492438067263, 7027.945808128821, 7052.612106334201, 5823.272090988627, 5824.388439213991, 6253.812385628438, 6300.716467759054, 6991.580505169706, 6162.661053642093, 6496.935030649633, 6759.831004224934, 6828.624425656637, 6832.419244364285, 7038.859222815413, 7047.823804404931, 7052.9647351762915, 7061.25753350725, 7068.3285087916565, 4381.802818873222, 4512.120136573337, 4514.548545145591, 4515.480719717275, 4530.524294949115, 5584.46947539983, 6005.939940600538, 6898.482613804085, 6920.373712177534, 6953.4089602384365, 6190.380961903774, 6633.800985970429, 6667.366600172976, 6677.465802735866, 6696.493289010129, 5889.646621202742, 6303.810885673253, 6623.867522649423, 6655.966720166562, 6658.300878407697, 5341.919871201857, 6259.280182778856, 6415.332013676384, 6590.637514936724, 6603.339666033358, 6114.877702446015, 6485.481161507162, 6630.370114839083, 6683.365080317362, 6766.729330826712, 6265.248170219603, 6485.448736857231, 6550.672466376567, 6556.311587283458, 6571.34286571339], "value": 4791.5813190821245, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.1034882327087, 860.1140824950302, 861.5433820075363, 870.5386145342968, 875.1817051057262, 14407.351669174886, 14996.850031499682, 16809.915950420247, 17082.231299591516, 17142.82857171428, 13147.539836105738, 13736.656583585416, 17906.64186716265, 17929.551761205978, 17945.192466339013, 982.7150126463335, 1023.4729114506035, 1026.5944951744057, 1030.7732298894232, 1031.8452232165198, 975.9072888075622, 1004.8643433136506, 1024.8462730590397, 1025.9794804103913, 1028.547439126782, 1011.9425200665322, 1048.8636477257985, 1054.620336678795, 1071.4160782373624, 1078.9352638841694, 1039.3867618105278, 1041.552132582989, 1041.7083216699336, 1045.1743122932912, 1045.2526443592792, 755.9848803023908, 775.2054144501931, 778.0, 779.3142035009156, 779.6667698236023, 14998.850011499964, 16427.58931026722, 17035.744463833053, 17206.225719842638, 17847.643047139114, 16603.252853621616, 17073.658526829393, 17268.481945541655, 17360.479185624452, 17412.564685882826, 14069.577912662637, 14137.293135343192, 14169.14985100887, 14291.285435728172, 14313.069650472686, 3262.6737326267184, 3435.1068722132177, 3493.807840568768, 3509.929801403983, 3513.2095278562374, 101661.933522659, 106145.46927265314, 114828.42585787017, 123094.0, 127054.0, 13481.123726957912, 13716.314184290944, 13784.310784460737, 13826.446942122404, 13858.099223550638, 22090.0, 25035.499290014097, 25707.0, 26105.08632197864, 26147.73852261453, 7304.926950730531, 8014.358851291882, 8083.959580202179, 8203.138670439637, 8226.0, 4021.0148513613767, 4034.0, 4113.691473139466, 4118.9588104119175, 4122.464079669624, 4313.288307429302, 4435.534268901783, 4447.266201076851, 4480.798364073625, 4498.955010449854, 869.3610196505564, 899.0515006667897, 907.0521305236044, 907.3285768531372, 907.568904770242, 631.207834168116, 652.8596351784389, 684.1004079635263, 684.226823689228, 689.1798759476208, 10877.728056798644, 11879.406029698654, 11885.821712674317, 12121.0, 12198.207116537396, 654.8526581519216, 656.7208936202095, 657.7796438193163, 674.2414783368711, 698.2947223304398, 4100.528439229467, 5129.461406552333, 5391.002664507, 5637.68992705397, 5692.715364231772, 98878.50560747294, 102210.0, 105663.47168264109, 106434.46782766037, 108147.0, 91242.08757912468, 94596.0540394601, 95507.52246238863, 97045.0, 97934.0, 58073.41926580764, 82264.9999999988, 83947.0, 84077.91065072108, 84168.0, 10001.1499022581, 10823.0, 11094.223404361617, 11214.215004949576, 11283.323000619988, 42506.36240456458, 43495.78252108719, 43532.12935741331, 44824.0, 45145.0, 40332.59667403406, 44842.77578612086, 55273.0, 55458.0, 56166.31501054975, 13934.930325348309, 14180.929095354457, 14206.715865682421, 14214.502492412716, 14252.144871307755, 15214.011089279162, 15383.76924346158, 15421.768673469682, 15529.844701553291, 15689.137097459292, 13831.723365532835, 13838.0, 13851.0, 13957.3719182635, 14020.018598698402, 14018.929905350407, 14031.789523157366, 14110.153390796586, 14165.150090994573, 14284.785728213878, 13820.723585528434, 13893.930530347283, 13938.442462301395, 13972.231527266098, 14016.29918504091, 10270.845937311096, 10349.793004140025, 10539.63111291101, 10727.785444291227, 10768.46157692128, 11161.497732602214, 11594.0, 11596.0, 11737.237079589799, 11877.0], "value": 8108.532570154347, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 117.0, 116.0, 117.0, 115.0, 113.0, 114.0, 115.0, 118.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 112.0, 108.0, 148.0, 110.0, 111.0, 110.0, 109.0, 153.0, 98.0], "value": 119.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 198.0, 195.0, 208.0, 252.0, 249.0, 199.0, 199.0, 197.0, 200.0], "value": 209.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 61.0, 60.0, 82.0, 59.0, 60.0, 59.0, 63.0, 79.0, 61.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 64.0, 92.0, 99.0, 98.0, 101.0, 107.0, 72.0, 70.0, 88.0], "value": 86.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 91.0, 88.0, 92.0, 88.0, 88.0, 89.0, 89.0, 82.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 99.0, 96.0, 124.0, 102.0, 150.0, 138.0, 96.0, 149.0], "value": 114.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 102.0, 143.0, 93.0, 92.0, 96.0, 94.0, 141.0, 94.0, 144.0], "value": 109.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 50.0, 51.0, 51.0, 51.0, 53.0, 51.0, 53.0, 52.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 65.0, 62.0, 67.0, 86.0, 88.0, 82.0, 78.0, 59.0, 58.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 83.0, 80.0, 84.0, 81.0, 85.0, 86.0, 84.0, 85.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 147.0, 148.0, 143.0, 155.0, 153.0, 143.0, 231.0, 133.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 185.0, 188.0, 185.0, 192.0, 182.0, 184.0, 186.0, 199.0], "value": 187.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 70.0, 76.0, 89.0, 90.0, 92.0, 71.0, 69.0, 71.0], "value": 76.4, "unit": "score"}]}]} 23:16:37 INFO - Return code: 0 23:16:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:16:37 ERROR - # TBPL SUCCESS # 23:16:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:16:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:16:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:16:37 INFO - 2017-04-18 23:16:37.830 screenresolution[1664:10745] starting screenresolution argv=screenresolution get 23:16:37 INFO - 2017-04-18 23:16:37.832 screenresolution[1664:10745] Display 0: 1600x1200x32@60 23:16:37 INFO - 2017-04-18 23:16:37.839 screenresolution[1665:10748] starting screenresolution argv=screenresolution list 23:16:37 INFO - Available Modes on Display 0 23:16:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:16:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:16:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:16:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:16:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:16:38 INFO - Intel Iris: 23:16:38 INFO - Chipset Model: Intel Iris 23:16:38 INFO - Type: GPU 23:16:38 INFO - Bus: Built-In 23:16:38 INFO - VRAM (Dynamic, Max): 1536 MB 23:16:38 INFO - Vendor: Intel (0x8086) 23:16:38 INFO - Device ID: 0x0a2e 23:16:38 INFO - Revision ID: 0x0009 23:16:38 INFO - Displays: 23:16:38 INFO - Display: 23:16:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:16:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:16:38 INFO - Display Serial Number: 1600x1200 60 23:16:38 INFO - Main Display: Yes 23:16:38 INFO - Mirror: Off 23:16:38 INFO - Online: Yes 23:16:38 INFO - Rotation: Supported 23:16:38 INFO - Return code: 0 23:16:38 INFO - Running post-action listener: _resource_record_post_action 23:16:38 INFO - [mozharness: 2017-04-19 06:16:38.088680Z] Finished run-tests step (success) 23:16:38 INFO - Running post-run listener: _resource_record_post_run 23:16:38 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 39767040; Write bytes: 517555200; Read time: 256; Write time: 1623 23:16:38 INFO - TinderboxPrint: CPU usage
54.4% 23:16:38 INFO - TinderboxPrint: I/O read bytes / time
39,767,040 / 256 23:16:38 INFO - TinderboxPrint: I/O write bytes / time
517,555,200 / 1,623 23:16:38 INFO - TinderboxPrint: CPU idle
2,187.2 (45.6%) 23:16:38 INFO - TinderboxPrint: CPU system
968.7 (20.2%) 23:16:38 INFO - TinderboxPrint: CPU user
1,637.8 (34.2%) 23:16:38 INFO - TinderboxPrint: Swap in / out
248,172,544 / 0 23:16:38 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206053888; Write bytes: 204400128; Read time: 16178; Write time: 413 23:16:38 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 37583872; Write bytes: 306105856; Read time: 234; Write time: 1186 23:16:38 INFO - Running post-run listener: _upload_blobber_files 23:16:38 INFO - Blob upload gear active. 23:16:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:16:38 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 23:16:38 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'] 23:16:38 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 23:16:38 INFO - (blobuploader) - INFO - Open directory for files ... 23:16:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:16:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:16:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:16:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:16:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:16:40 INFO - (blobuploader) - INFO - Done attempting. 23:16:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:16:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:16:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:16:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:16:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:16:41 INFO - (blobuploader) - INFO - Done attempting. 23:16:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:16:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:16:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:16:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:16:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:16:42 INFO - (blobuploader) - INFO - Done attempting. 23:16:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:16:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:16:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:16:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:16:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:16:44 INFO - (blobuploader) - INFO - Done attempting. 23:16:44 INFO - (blobuploader) - INFO - Iteration through files over. 23:16:44 INFO - Return code: 0 23:16:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:16:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:16:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/87fb150cc01b096f740b62bd61090648030bbe73c8368e9dafa089a82f85480e160352b0f9bc0cf14c3bea8a57992c3960d257199af6875b99c376a87b580c0b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8d111a9e12c757e1a32effbc1154049edb2dd32d7e7e7de0835166ad54ed918ac8aaa38152df2d12f4d04d802c7e3addb1d7bcf0c0359dcedf11577008faad22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7a98a7caa329b12fa8a0198ed20386c3ca5886987788208c3e923010b0ebe5108497dd6810fa2cd25a0c710ddebd9e48df2cf6f0d8a5f82c7ef61f1c4053cd67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5c3e94bd0c6444dec395dad985696592f3e7de5a26a8db66fd0c3e476d78cb561454fc3ec82f86f10b529cee8904fa0732b111082b4a979db3c730e7966733d9"} 23:16:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:16:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:16:44 INFO - Contents: 23:16:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/87fb150cc01b096f740b62bd61090648030bbe73c8368e9dafa089a82f85480e160352b0f9bc0cf14c3bea8a57992c3960d257199af6875b99c376a87b580c0b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8d111a9e12c757e1a32effbc1154049edb2dd32d7e7e7de0835166ad54ed918ac8aaa38152df2d12f4d04d802c7e3addb1d7bcf0c0359dcedf11577008faad22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7a98a7caa329b12fa8a0198ed20386c3ca5886987788208c3e923010b0ebe5108497dd6810fa2cd25a0c710ddebd9e48df2cf6f0d8a5f82c7ef61f1c4053cd67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5c3e94bd0c6444dec395dad985696592f3e7de5a26a8db66fd0c3e476d78cb561454fc3ec82f86f10b529cee8904fa0732b111082b4a979db3c730e7966733d9"} 23:16:44 INFO - Running post-run listener: copy_logs_to_upload_dir 23:16:44 INFO - Copying logs to upload dir... 23:16:44 INFO - mkdir: /builds/slave/test/build/upload/logs 23:16:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.129044 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-18 23:16:44.097325) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-18 23:16:44.101072) ========= 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.cIrvE0R0jQ/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.B05E5medgy/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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/87fb150cc01b096f740b62bd61090648030bbe73c8368e9dafa089a82f85480e160352b0f9bc0cf14c3bea8a57992c3960d257199af6875b99c376a87b580c0b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8d111a9e12c757e1a32effbc1154049edb2dd32d7e7e7de0835166ad54ed918ac8aaa38152df2d12f4d04d802c7e3addb1d7bcf0c0359dcedf11577008faad22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7a98a7caa329b12fa8a0198ed20386c3ca5886987788208c3e923010b0ebe5108497dd6810fa2cd25a0c710ddebd9e48df2cf6f0d8a5f82c7ef61f1c4053cd67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5c3e94bd0c6444dec395dad985696592f3e7de5a26a8db66fd0c3e476d78cb561454fc3ec82f86f10b529cee8904fa0732b111082b4a979db3c730e7966733d9"} build_url:https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011395 build_url: 'https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/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/87fb150cc01b096f740b62bd61090648030bbe73c8368e9dafa089a82f85480e160352b0f9bc0cf14c3bea8a57992c3960d257199af6875b99c376a87b580c0b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8d111a9e12c757e1a32effbc1154049edb2dd32d7e7e7de0835166ad54ed918ac8aaa38152df2d12f4d04d802c7e3addb1d7bcf0c0359dcedf11577008faad22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7a98a7caa329b12fa8a0198ed20386c3ca5886987788208c3e923010b0ebe5108497dd6810fa2cd25a0c710ddebd9e48df2cf6f0d8a5f82c7ef61f1c4053cd67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5c3e94bd0c6444dec395dad985696592f3e7de5a26a8db66fd0c3e476d78cb561454fc3ec82f86f10b529cee8904fa0732b111082b4a979db3c730e7966733d9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-18 23:16:44.136277) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 23:16:44.136699) ========= 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.cIrvE0R0jQ/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.B05E5medgy/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.005104 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 23:16:44.185853) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-18 23:16:44.186455) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-18 23:16:44.187136) ========= ========= Total master_lag: 0.58 =========