builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0301 starttime: 1483993390.2 results: success (0) buildid: 20170109105308 builduid: 756eba2d5ee24e2f89eae24097c91f4a revision: 1f3bb12e7ea66181f264cb8f5f4ab125f6b56fbf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:10.200474) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:10.201163) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:10.201425) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:10.247876) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:10.248184) ========= 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.OIlsDNZ1YR/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.9NUlnMou5F/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-01-09 12:23:10-- 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% 8.39M=0.001s 2017-01-09 12:23:10 (8.39 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.687776 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:10.963101) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:10.963445) ========= 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.OIlsDNZ1YR/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.9NUlnMou5F/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.020011 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:11.012194) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 12:23:11.012558) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1f3bb12e7ea66181f264cb8f5f4ab125f6b56fbf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1f3bb12e7ea66181f264cb8f5f4ab125f6b56fbf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OIlsDNZ1YR/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.9NUlnMou5F/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-01-09 12:23:11,103 truncating revision to first 12 chars 2017-01-09 12:23:11,103 Setting DEBUG logging. 2017-01-09 12:23:11,103 attempt 1/10 2017-01-09 12:23:11,103 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1f3bb12e7ea6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 12:23:12,202 unpacking tar archive at: mozilla-aurora-1f3bb12e7ea6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.359115 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 12:23:12.394967) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:12.395288) ========= script_repo_revision: 1f3bb12e7ea66181f264cb8f5f4ab125f6b56fbf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:12.396313) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:12.397096) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 12:23:12.420901) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-09 12:23:12.421241) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OIlsDNZ1YR/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.9NUlnMou5F/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 12:23:12 INFO - MultiFileLogger online at 20170109 12:23:12 in /builds/slave/test 12:23:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:23:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:23:12 INFO - {'append_to_log': False, 12:23:12 INFO - 'base_work_dir': '/builds/slave/test', 12:23:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:23:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:23:12 INFO - 'branch': 'Mozilla-Aurora', 12:23:12 INFO - 'buildbot_json_path': 'buildprops.json', 12:23:12 INFO - 'config_files': ('talos/mac_config.py',), 12:23:12 INFO - 'default_actions': ('clobber', 12:23:12 INFO - 'read-buildbot-config', 12:23:12 INFO - 'download-and-extract', 12:23:12 INFO - 'populate-webroot', 12:23:12 INFO - 'create-virtualenv', 12:23:12 INFO - 'install', 12:23:12 INFO - 'run-tests'), 12:23:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:23:12 INFO - 'download_minidump_stackwalk': True, 12:23:12 INFO - 'download_symbols': 'ondemand', 12:23:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:23:12 INFO - 'tooltool.py': '/tools/tooltool.py', 12:23:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:23:12 INFO - '/tools/misc-python/virtualenv.py')}, 12:23:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:23:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:23:12 INFO - 'installer_path': 'installer.exe', 12:23:12 INFO - 'log_level': 'info', 12:23:12 INFO - 'log_name': 'talos', 12:23:12 INFO - 'log_to_console': True, 12:23:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:23:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:23:12 INFO - 'opt_config_files': (), 12:23:12 INFO - 'pip_index': False, 12:23:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:23:12 INFO - 'cmd': ('bash', 12:23:12 INFO - '-c', 12:23:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:23:12 INFO - 'enabled': True, 12:23:12 INFO - 'halt_on_failure': False, 12:23:12 INFO - 'name': 'check_screen_resolution'},), 12:23:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:23:12 INFO - 'cmd': ('bash', 12:23:12 INFO - '-c', 12:23:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:23:12 INFO - 'enabled': True, 12:23:12 INFO - 'halt_on_failure': False, 12:23:12 INFO - 'name': 'check_screen_resolution'},), 12:23:12 INFO - 'run_cmd_checks_enabled': True, 12:23:12 INFO - 'sps_profile': False, 12:23:12 INFO - 'sps_profile_interval': 0, 12:23:12 INFO - 'suite': 'dromaeojs-e10s', 12:23:12 INFO - 'system_bits': '32', 12:23:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:23:12 INFO - 'title': 't-yosemite-r7-0301', 12:23:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:23:12 INFO - 'use_talos_json': True, 12:23:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:23:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:23:12 INFO - 'work_dir': 'build'} 12:23:12 INFO - [mozharness: 2017-01-09 20:23:12.635218Z] Running clobber step. 12:23:12 INFO - Running pre-action listener: _resource_record_pre_action 12:23:12 INFO - Running main action method: clobber 12:23:12 INFO - rmtree: /builds/slave/test/build 12:23:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:23:13 INFO - Running post-action listener: _resource_record_post_action 12:23:13 INFO - [mozharness: 2017-01-09 20:23:13.535580Z] Finished clobber step (success) 12:23:13 INFO - [mozharness: 2017-01-09 20:23:13.535707Z] Running read-buildbot-config step. 12:23:13 INFO - Running pre-action listener: _resource_record_pre_action 12:23:13 INFO - Running main action method: read_buildbot_config 12:23:13 INFO - Using buildbot properties: 12:23:13 INFO - { 12:23:13 INFO - "project": "", 12:23:13 INFO - "product": "firefox", 12:23:13 INFO - "script_repo_revision": "production", 12:23:13 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 12:23:13 INFO - "repository": "", 12:23:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 12:23:13 INFO - "buildid": "20170109105308", 12:23:13 INFO - "pgo_build": "False", 12:23:13 INFO - "basedir": "/builds/slave/test", 12:23:13 INFO - "buildnumber": 1, 12:23:13 INFO - "slavename": "t-yosemite-r7-0301", 12:23:13 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:23:13 INFO - "platform": "yosemite_r7", 12:23:13 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:23:13 INFO - "branch": "mozilla-aurora", 12:23:13 INFO - "repo_path": "releases/mozilla-aurora", 12:23:13 INFO - "revision": "1f3bb12e7ea66181f264cb8f5f4ab125f6b56fbf", 12:23:13 INFO - "stage_platform": "macosx64", 12:23:13 INFO - "builduid": "756eba2d5ee24e2f89eae24097c91f4a", 12:23:13 INFO - "slavebuilddir": "test" 12:23:13 INFO - } 12:23:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 12:23:13 INFO - Running post-action listener: _resource_record_post_action 12:23:13 INFO - [mozharness: 2017-01-09 20:23:13.538975Z] Finished read-buildbot-config step (success) 12:23:13 INFO - [mozharness: 2017-01-09 20:23:13.539107Z] Running download-and-extract step. 12:23:13 INFO - Running pre-action listener: _resource_record_pre_action 12:23:13 INFO - Running main action method: download_and_extract 12:23:13 INFO - mkdir: /builds/slave/test/build/tests 12:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:13 INFO - trying https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 12:23:13 INFO - Downloading https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 12:23:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 12:23:14 INFO - Downloaded 1199 bytes. 12:23:14 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 12:23:14 INFO - Using the following test package requirements: 12:23:14 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:23:14 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:14 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 12:23:14 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:14 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 12:23:14 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:14 INFO - u'jsshell-mac.zip'], 12:23:14 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:14 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 12:23:14 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:23:14 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:14 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 12:23:14 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:14 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 12:23:14 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:14 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 12:23:14 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:23:14 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 12:23:14 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:23:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:23:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:23:14 INFO - Fetch https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:23:15 INFO - Http code: 200 12:23:15 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 12:23:15 INFO - x-amz-cf-id: lsrkTxd0A1851MDxUPyXMI6tGUm2xsGg82fwewUR_bCzjtckDBYSyA== 12:23:15 INFO - x-amz-version-id: AKwtWn1UBDH.BkbjT7hj3yOr830WJ_71 12:23:15 INFO - Expected file size: 16270844 12:23:15 INFO - Obtained file size: 16270844 12:23:22 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:23:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:23:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:23:22 INFO - Fetch https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:23:23 INFO - Http code: 200 12:23:23 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 12:23:23 INFO - x-amz-cf-id: ItyH_Um84xAO8iMQ8iFI9ka-CF_62YuDEB51F8t7xuviZZ8W1GlYWA== 12:23:23 INFO - x-amz-version-id: AKwtWn1UBDH.BkbjT7hj3yOr830WJ_71 12:23:23 INFO - Expected file size: 16270844 12:23:23 INFO - Obtained file size: 16270844 12:23:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 12:23:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:23:29 INFO - Fetch https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 12:23:30 INFO - Http code: 200 12:23:30 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 12:23:30 INFO - x-amz-cf-id: iUrlNvNtle3n6Ws-VoKwP85dYDYIZogAju2hvTirNkoohE8FzoSziA== 12:23:30 INFO - x-amz-version-id: hj3QKO1nC8qE1wZv9RflNYOciDzr3Dmf 12:23:30 INFO - Expected file size: 14409038 12:23:30 INFO - Obtained file size: 14409038 12:23:31 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')]} 12:23:31 INFO - trying https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:23:31 INFO - Downloading https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 12:23:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 12:23:33 INFO - Downloaded 109876819 bytes. 12:23:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:23:33 INFO - mkdir: /builds/slave/test/properties 12:23:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:23:33 INFO - Writing to file /builds/slave/test/properties/build_url 12:23:33 INFO - Contents: 12:23:33 INFO - build_url:https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:23:34 INFO - Running post-action listener: _resource_record_post_action 12:23:34 INFO - Running post-action listener: set_extra_try_arguments 12:23:34 INFO - [mozharness: 2017-01-09 20:23:34.334163Z] Finished download-and-extract step (success) 12:23:34 INFO - [mozharness: 2017-01-09 20:23:34.334307Z] Running populate-webroot step. 12:23:34 INFO - Running pre-action listener: _resource_record_pre_action 12:23:34 INFO - Running main action method: populate_webroot 12:23:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:23:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:23:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:23:34 INFO - '--tppagecycles', 12:23:34 INFO - '7'], 12:23:34 INFO - 'tests': ['tsvgm']}}, 12:23:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:23:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:23:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:23:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:23:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:23:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:23:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:34 INFO - 'talos_options': ['--disable-e10s'], 12:23:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:34 INFO - 'talos_options': ['--disable-e10s'], 12:23:34 INFO - 'tests': ['damp', 'tps']}, 12:23:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:34 INFO - 'tests': ['damp', 'tps']}, 12:23:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:23:34 INFO - 'tests': ['dromaeo_dom']}, 12:23:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:23:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:23:34 INFO - 'tests': ['basic_compositor_video']}, 12:23:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:23:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:23:34 INFO - 'tests': ['a11yr', 12:23:34 INFO - 'ts_paint', 12:23:34 INFO - 'tpaint', 12:23:34 INFO - 'sessionrestore', 12:23:34 INFO - 'sessionrestore_no_auto_restore', 12:23:34 INFO - 'tabpaint']}, 12:23:34 INFO - 'other-e10s': {'tests': ['a11yr', 12:23:34 INFO - 'ts_paint', 12:23:34 INFO - 'tpaint', 12:23:34 INFO - 'sessionrestore', 12:23:34 INFO - 'sessionrestore_no_auto_restore', 12:23:34 INFO - 'tabpaint']}, 12:23:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:23:34 INFO - 'ts_paint', 12:23:34 INFO - 'tpaint', 12:23:34 INFO - 'sessionrestore', 12:23:34 INFO - 'sessionrestore_no_auto_restore', 12:23:34 INFO - 'tabpaint']}, 12:23:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:23:34 INFO - 'ts_paint', 12:23:34 INFO - 'tpaint', 12:23:34 INFO - 'sessionrestore', 12:23:34 INFO - 'sessionrestore_no_auto_restore', 12:23:34 INFO - 'tabpaint']}, 12:23:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:23:34 INFO - 'tests': ['a11yr', 12:23:34 INFO - 'ts_paint', 12:23:34 INFO - 'tpaint', 12:23:34 INFO - 'sessionrestore', 12:23:34 INFO - 'sessionrestore_no_auto_restore', 12:23:34 INFO - 'tabpaint']}, 12:23:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:23:34 INFO - 'tests': ['a11yr', 12:23:34 INFO - 'ts_paint', 12:23:34 INFO - 'tpaint', 12:23:34 INFO - 'sessionrestore', 12:23:34 INFO - 'sessionrestore_no_auto_restore', 12:23:34 INFO - 'tabpaint']}, 12:23:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:23:34 INFO - 'tests': ['tsvgx', 12:23:34 INFO - 'tsvgr_opacity', 12:23:34 INFO - 'tart', 12:23:34 INFO - 'tscrollx', 12:23:34 INFO - 'cart']}, 12:23:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:23:34 INFO - 'tsvgr_opacity', 12:23:34 INFO - 'tart', 12:23:34 INFO - 'tscrollx', 12:23:34 INFO - 'cart']}, 12:23:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:34 INFO - 'talos_options': ['--disable-e10s'], 12:23:34 INFO - 'tests': ['tp5o']}, 12:23:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:34 INFO - 'tests': ['tp5o']}, 12:23:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:34 INFO - 'talos_options': ['--disable-e10s', 12:23:34 INFO - '--xperf_path', 12:23:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:34 INFO - 'tests': ['tp5n']}, 12:23:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:34 INFO - 'talos_options': ['--xperf_path', 12:23:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:34 INFO - 'tests': ['tp5n']}}, 12:23:34 INFO - 'talos.zip': {'path': '', 12:23:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:23:34 INFO - Running post-action listener: _resource_record_post_action 12:23:34 INFO - [mozharness: 2017-01-09 20:23:34.352829Z] Finished populate-webroot step (success) 12:23:34 INFO - [mozharness: 2017-01-09 20:23:34.352962Z] Running create-virtualenv step. 12:23:34 INFO - Running pre-action listener: _resource_record_pre_action 12:23:34 INFO - Running main action method: create_virtualenv 12:23:34 INFO - Creating virtualenv /builds/slave/test/build/venv 12:23:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:23:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:23:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:23:34 INFO - Using real prefix '/tools/python27' 12:23:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:23:35 INFO - Installing distribute.............................................................................................................................................................................................done. 12:23:38 INFO - Installing pip.................done. 12:23:38 INFO - Return code: 0 12:23:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:23:38 INFO - Reading from file tmpfile_stdout 12:23:38 INFO - Output received: 12:23:38 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:23:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ae3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f909bca59d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078f7d30>, '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 0x10775ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078efb20>, '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.OIlsDNZ1YR/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.9NUlnMou5F/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 12:23:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:23:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIlsDNZ1YR/Render', 12:23:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:38 INFO - 'HOME': '/Users/cltbld', 12:23:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:38 INFO - 'LOGNAME': 'cltbld', 12:23:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:38 INFO - 'MOZ_NO_REMOTE': '1', 12:23:38 INFO - 'NO_EM_RESTART': '1', 12:23:38 INFO - 'PAGER': '/bin/cat', 12:23:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:38 INFO - 'PWD': '/builds/slave/test', 12:23:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:38 INFO - 'SHELL': '/bin/bash', 12:23:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9NUlnMou5F/Listeners', 12:23:38 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 12:23:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:38 INFO - 'USER': 'cltbld', 12:23:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:38 INFO - 'XPC_FLAGS': '0x0', 12:23:38 INFO - 'XPC_SERVICE_NAME': '0', 12:23:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:38 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:23:38 INFO - Cleaning up... 12:23:38 INFO - Return code: 0 12:23:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ae3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f909bca59d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078f7d30>, '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 0x10775ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078efb20>, '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.OIlsDNZ1YR/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.9NUlnMou5F/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 12:23:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:23:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIlsDNZ1YR/Render', 12:23:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:38 INFO - 'HOME': '/Users/cltbld', 12:23:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:38 INFO - 'LOGNAME': 'cltbld', 12:23:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:38 INFO - 'MOZ_NO_REMOTE': '1', 12:23:38 INFO - 'NO_EM_RESTART': '1', 12:23:38 INFO - 'PAGER': '/bin/cat', 12:23:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:38 INFO - 'PWD': '/builds/slave/test', 12:23:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:38 INFO - 'SHELL': '/bin/bash', 12:23:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9NUlnMou5F/Listeners', 12:23:38 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 12:23:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:38 INFO - 'USER': 'cltbld', 12:23:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:38 INFO - 'XPC_FLAGS': '0x0', 12:23:38 INFO - 'XPC_SERVICE_NAME': '0', 12:23:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:38 INFO - Downloading/unpacking psutil>=3.1.1 12:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:23:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:42 INFO - Installing collected packages: psutil 12:23:42 INFO - Running setup.py install for psutil 12:23:42 INFO - building 'psutil._psutil_osx' extension 12:23:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:23:43 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 12:23:43 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 12:23:43 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 12:23:43 INFO - building 'psutil._psutil_posix' extension 12:23:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:23:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:23:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:23:43 INFO - ^ 12:23:43 INFO - 1 warning generated. 12:23:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:23:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:43 INFO - Successfully installed psutil 12:23:43 INFO - Cleaning up... 12:23:43 INFO - Return code: 0 12:23:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:23:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ae3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f909bca59d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078f7d30>, '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 0x10775ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078efb20>, '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.OIlsDNZ1YR/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.9NUlnMou5F/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 12:23:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:23:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:23:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIlsDNZ1YR/Render', 12:23:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:43 INFO - 'HOME': '/Users/cltbld', 12:23:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:43 INFO - 'LOGNAME': 'cltbld', 12:23:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:43 INFO - 'MOZ_NO_REMOTE': '1', 12:23:43 INFO - 'NO_EM_RESTART': '1', 12:23:43 INFO - 'PAGER': '/bin/cat', 12:23:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:43 INFO - 'PWD': '/builds/slave/test', 12:23:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:43 INFO - 'SHELL': '/bin/bash', 12:23:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9NUlnMou5F/Listeners', 12:23:43 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 12:23:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:43 INFO - 'USER': 'cltbld', 12:23:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:43 INFO - 'XPC_FLAGS': '0x0', 12:23:43 INFO - 'XPC_SERVICE_NAME': '0', 12:23:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:44 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 12:23:44 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 12:23:44 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 12:23:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:23:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:23:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:23:46 INFO - Installing collected packages: mozsystemmonitor 12:23:46 INFO - Running setup.py install for mozsystemmonitor 12:23:46 INFO - Successfully installed mozsystemmonitor 12:23:46 INFO - Cleaning up... 12:23:46 INFO - Return code: 0 12:23:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:23:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ae3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f909bca59d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078f7d30>, '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 0x10775ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078efb20>, '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.OIlsDNZ1YR/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.9NUlnMou5F/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 12:23:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:23:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:23:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIlsDNZ1YR/Render', 12:23:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:46 INFO - 'HOME': '/Users/cltbld', 12:23:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:46 INFO - 'LOGNAME': 'cltbld', 12:23:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:46 INFO - 'MOZ_NO_REMOTE': '1', 12:23:46 INFO - 'NO_EM_RESTART': '1', 12:23:46 INFO - 'PAGER': '/bin/cat', 12:23:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:46 INFO - 'PWD': '/builds/slave/test', 12:23:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:46 INFO - 'SHELL': '/bin/bash', 12:23:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9NUlnMou5F/Listeners', 12:23:46 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 12:23:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:46 INFO - 'USER': 'cltbld', 12:23:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:46 INFO - 'XPC_FLAGS': '0x0', 12:23:46 INFO - 'XPC_SERVICE_NAME': '0', 12:23:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:47 INFO - Downloading/unpacking jsonschema==2.5.1 12:23:47 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 12:23:47 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 12:23:47 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 12:23:47 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 12:23:47 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 12:23:47 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 12:23:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:23:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:50 INFO - Downloading functools32-3.2.3-2.tar.gz 12:23:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:23:50 INFO - warning: no files found matching '*.txt' 12:23:50 INFO - no previously-included directories found matching 'build' 12:23:50 INFO - no previously-included directories found matching 'dist' 12:23:50 INFO - no previously-included directories found matching '.git*' 12:23:50 INFO - Installing collected packages: jsonschema, functools32 12:23:50 INFO - Running setup.py install for functools32 12:23:50 INFO - warning: no files found matching '*.txt' 12:23:50 INFO - no previously-included directories found matching 'build' 12:23:50 INFO - no previously-included directories found matching 'dist' 12:23:50 INFO - no previously-included directories found matching '.git*' 12:23:50 INFO - Successfully installed jsonschema functools32 12:23:50 INFO - Cleaning up... 12:23:50 INFO - Return code: 0 12:23:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:23:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ae3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f909bca59d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078f7d30>, '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 0x10775ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078efb20>, '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.OIlsDNZ1YR/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.9NUlnMou5F/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 12:23:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:23:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:23:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIlsDNZ1YR/Render', 12:23:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:50 INFO - 'HOME': '/Users/cltbld', 12:23:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:50 INFO - 'LOGNAME': 'cltbld', 12:23:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:50 INFO - 'MOZ_NO_REMOTE': '1', 12:23:50 INFO - 'NO_EM_RESTART': '1', 12:23:50 INFO - 'PAGER': '/bin/cat', 12:23:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:50 INFO - 'PWD': '/builds/slave/test', 12:23:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:50 INFO - 'SHELL': '/bin/bash', 12:23:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9NUlnMou5F/Listeners', 12:23:50 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 12:23:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:50 INFO - 'USER': 'cltbld', 12:23:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:50 INFO - 'XPC_FLAGS': '0x0', 12:23:50 INFO - 'XPC_SERVICE_NAME': '0', 12:23:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:23:50 INFO - Cleaning up... 12:23:50 INFO - Return code: 0 12:23:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:23:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ae3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f909bca59d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078f7d30>, '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 0x10775ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078efb20>, '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.OIlsDNZ1YR/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.9NUlnMou5F/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 12:23:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:23:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:23:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIlsDNZ1YR/Render', 12:23:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:50 INFO - 'HOME': '/Users/cltbld', 12:23:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:50 INFO - 'LOGNAME': 'cltbld', 12:23:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:50 INFO - 'MOZ_NO_REMOTE': '1', 12:23:50 INFO - 'NO_EM_RESTART': '1', 12:23:50 INFO - 'PAGER': '/bin/cat', 12:23:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:50 INFO - 'PWD': '/builds/slave/test', 12:23:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:50 INFO - 'SHELL': '/bin/bash', 12:23:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9NUlnMou5F/Listeners', 12:23:50 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 12:23:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:50 INFO - 'USER': 'cltbld', 12:23:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:50 INFO - 'XPC_FLAGS': '0x0', 12:23:50 INFO - 'XPC_SERVICE_NAME': '0', 12:23:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:51 INFO - Downloading/unpacking blobuploader==1.2.4 12:23:51 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 12:23:51 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 12:23:51 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 12:23:51 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 12:23:51 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 12:23:51 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 12:23:53 INFO - Downloading blobuploader-1.2.4.tar.gz 12:23:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:23:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:23:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:23:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:54 INFO - Downloading docopt-0.6.1.tar.gz 12:23:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:23:55 INFO - Installing collected packages: blobuploader, requests, docopt 12:23:55 INFO - Running setup.py install for blobuploader 12:23:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:23:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:23:55 INFO - Running setup.py install for requests 12:23:55 INFO - Running setup.py install for docopt 12:23:55 INFO - Successfully installed blobuploader requests docopt 12:23:55 INFO - Cleaning up... 12:23:55 INFO - Return code: 0 12:23:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ae3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f909bca59d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078f7d30>, '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 0x10775ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078efb20>, '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.OIlsDNZ1YR/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.9NUlnMou5F/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 12:23:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:23:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:23:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIlsDNZ1YR/Render', 12:23:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:55 INFO - 'HOME': '/Users/cltbld', 12:23:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:55 INFO - 'LOGNAME': 'cltbld', 12:23:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:55 INFO - 'MOZ_NO_REMOTE': '1', 12:23:55 INFO - 'NO_EM_RESTART': '1', 12:23:55 INFO - 'PAGER': '/bin/cat', 12:23:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:55 INFO - 'PWD': '/builds/slave/test', 12:23:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:55 INFO - 'SHELL': '/bin/bash', 12:23:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9NUlnMou5F/Listeners', 12:23:55 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 12:23:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:55 INFO - 'USER': 'cltbld', 12:23:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:55 INFO - 'XPC_FLAGS': '0x0', 12:23:55 INFO - 'XPC_SERVICE_NAME': '0', 12:23:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:56 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-O0Scgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:23:56 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-yDiFMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:23:56 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-mcr1j6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:23:56 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-GADd0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:23:56 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-ARXuBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:23:56 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-ipd2Py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:23:56 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-T1nA16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:23:56 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-ygZEyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:23:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-XFrYFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:23:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-i7c6nv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:23:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-_0ilpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:23:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-wI_VGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:23:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-zqn88D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:23:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-SbqUf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-jSm4R3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:23:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-tlBozS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:23:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Pc68eP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:23:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:23:57 INFO - Running setup.py install for manifestparser 12:23:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:23:58 INFO - Running setup.py install for mozcrash 12:23:58 INFO - Running setup.py install for mozdebug 12:23:58 INFO - Running setup.py install for mozdevice 12:23:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:23:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:23:58 INFO - Running setup.py install for mozfile 12:23:58 INFO - Running setup.py install for mozhttpd 12:23:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:23:58 INFO - Running setup.py install for mozinfo 12:23:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Running setup.py install for mozInstall 12:23:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Running setup.py install for mozleak 12:23:59 INFO - Running setup.py install for mozlog 12:23:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Running setup.py install for moznetwork 12:23:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Running setup.py install for mozprocess 12:23:59 INFO - Running setup.py install for mozprofile 12:23:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:23:59 INFO - Running setup.py install for mozrunner 12:24:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:24:00 INFO - Running setup.py install for mozscreenshot 12:24:00 INFO - Running setup.py install for moztest 12:24:00 INFO - Running setup.py install for mozversion 12:24:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:24:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:24:00 INFO - Cleaning up... 12:24:00 INFO - Return code: 0 12:24:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:24:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:24:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ae3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f909bca59d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078f7d30>, '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 0x10775ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078efb20>, '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.OIlsDNZ1YR/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.9NUlnMou5F/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 12:24:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:24:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:24:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIlsDNZ1YR/Render', 12:24:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:24:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:24:00 INFO - 'HOME': '/Users/cltbld', 12:24:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:24:00 INFO - 'LOGNAME': 'cltbld', 12:24:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:00 INFO - 'MOZ_NO_REMOTE': '1', 12:24:00 INFO - 'NO_EM_RESTART': '1', 12:24:00 INFO - 'PAGER': '/bin/cat', 12:24:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:24:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:00 INFO - 'PWD': '/builds/slave/test', 12:24:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:24:00 INFO - 'SHELL': '/bin/bash', 12:24:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9NUlnMou5F/Listeners', 12:24:00 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 12:24:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:24:00 INFO - 'USER': 'cltbld', 12:24:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:24:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:24:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:00 INFO - 'XPC_FLAGS': '0x0', 12:24:00 INFO - 'XPC_SERVICE_NAME': '0', 12:24:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:24:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:24:00 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-kP8p_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:24:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:24:00 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-gTOQbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:24:01 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)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:24:01 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-oiDn2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:24:01 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)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:24:01 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-TJbV8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:24:01 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-gingJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:24:01 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-IRWzTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:24:01 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-TcBpex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:24:01 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Y3nciz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:24:01 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-SJnz7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:24:01 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-3XQUII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:24:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:24:01 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-7mGmJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:24:02 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)) 12:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:24:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-DUByUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:24:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-yrNiJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:24:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-68x8U6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:24:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Yg3eUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:24:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-uIujx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:24:02 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)) 12:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:24:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-zBSY_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:24:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:24:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:24:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:24:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:24:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:24:05 INFO - Downloading blessings-1.6.tar.gz 12:24:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:24:05 INFO - Installing collected packages: blessings 12:24:05 INFO - Running setup.py install for blessings 12:24:05 INFO - Successfully installed blessings 12:24:05 INFO - Cleaning up... 12:24:05 INFO - Return code: 0 12:24:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:24:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:24:05 INFO - Reading from file tmpfile_stdout 12:24:05 INFO - Current package versions: 12:24:05 INFO - blessings == 1.6 12:24:05 INFO - blobuploader == 1.2.4 12:24:05 INFO - docopt == 0.6.1 12:24:05 INFO - functools32 == 3.2.3-2 12:24:05 INFO - jsonschema == 2.5.1 12:24:05 INFO - manifestparser == 1.1 12:24:05 INFO - mozInstall == 1.12 12:24:05 INFO - mozcrash == 1.0 12:24:05 INFO - mozdebug == 0.1 12:24:05 INFO - mozdevice == 0.48 12:24:05 INFO - mozfile == 1.2 12:24:05 INFO - mozhttpd == 0.7 12:24:05 INFO - mozinfo == 0.9 12:24:05 INFO - mozleak == 0.1 12:24:05 INFO - mozlog == 3.4 12:24:05 INFO - moznetwork == 0.27 12:24:05 INFO - mozprocess == 0.23 12:24:05 INFO - mozprofile == 0.28 12:24:05 INFO - mozrunner == 6.13 12:24:05 INFO - mozscreenshot == 0.1 12:24:05 INFO - mozsystemmonitor == 0.3 12:24:05 INFO - moztest == 0.8 12:24:05 INFO - mozversion == 1.4 12:24:05 INFO - psutil == 3.1.1 12:24:05 INFO - requests == 1.2.3 12:24:05 INFO - wsgiref == 0.1.2 12:24:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:24:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:24:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:05 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 0x107ae3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f909bca59d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078f7d30>, '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 0x10775ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078efb20>, '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.OIlsDNZ1YR/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.9NUlnMou5F/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 12:24:05 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 12:24:05 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 12:24:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIlsDNZ1YR/Render', 12:24:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:24:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:24:05 INFO - 'HOME': '/Users/cltbld', 12:24:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:24:05 INFO - 'LOGNAME': 'cltbld', 12:24:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:05 INFO - 'MOZ_NO_REMOTE': '1', 12:24:05 INFO - 'NO_EM_RESTART': '1', 12:24:05 INFO - 'PAGER': '/bin/cat', 12:24:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:24:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:05 INFO - 'PWD': '/builds/slave/test', 12:24:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:24:05 INFO - 'SHELL': '/bin/bash', 12:24:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9NUlnMou5F/Listeners', 12:24:05 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 12:24:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:24:05 INFO - 'USER': 'cltbld', 12:24:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:24:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:24:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:05 INFO - 'XPC_FLAGS': '0x0', 12:24:05 INFO - 'XPC_SERVICE_NAME': '0', 12:24:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:24:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:24:06 INFO - Cleaning up... 12:24:06 INFO - Return code: 0 12:24:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:24:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:24:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ae3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f909bca59d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078f7d30>, '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 0x10775ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078efb20>, '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.OIlsDNZ1YR/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.9NUlnMou5F/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 12:24:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:24:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:24:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIlsDNZ1YR/Render', 12:24:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:24:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:24:06 INFO - 'HOME': '/Users/cltbld', 12:24:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:24:06 INFO - 'LOGNAME': 'cltbld', 12:24:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:06 INFO - 'MOZ_NO_REMOTE': '1', 12:24:06 INFO - 'NO_EM_RESTART': '1', 12:24:06 INFO - 'PAGER': '/bin/cat', 12:24:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:24:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:06 INFO - 'PWD': '/builds/slave/test', 12:24:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:24:06 INFO - 'SHELL': '/bin/bash', 12:24:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9NUlnMou5F/Listeners', 12:24:06 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 12:24:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:24:06 INFO - 'USER': 'cltbld', 12:24:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:24:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:24:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:06 INFO - 'XPC_FLAGS': '0x0', 12:24:06 INFO - 'XPC_SERVICE_NAME': '0', 12:24:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:24:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:24:06 INFO - Cleaning up... 12:24:06 INFO - Return code: 0 12:24:06 INFO - Running post-action listener: _resource_record_post_action 12:24:06 INFO - Running post-action listener: _start_resource_monitoring 12:24:06 INFO - Starting resource monitoring. 12:24:06 INFO - [mozharness: 2017-01-09 20:24:06.520491Z] Finished create-virtualenv step (success) 12:24:06 INFO - [mozharness: 2017-01-09 20:24:06.521556Z] Running install step. 12:24:06 INFO - Running pre-action listener: _resource_record_pre_action 12:24:06 INFO - Running main action method: install 12:24:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:24:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:24:06 INFO - Reading from file tmpfile_stdout 12:24:06 INFO - Detecting whether we're running mozinstall >=1.0... 12:24:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:24:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:24:06 INFO - Reading from file tmpfile_stdout 12:24:06 INFO - Output received: 12:24:06 INFO - Usage: mozinstall [options] installer 12:24:06 INFO - Options: 12:24:06 INFO - -h, --help show this help message and exit 12:24:06 INFO - -d DEST, --destination=DEST 12:24:06 INFO - Directory to install application into. [default: 12:24:06 INFO - "/builds/slave/test"] 12:24:06 INFO - --app=APP Application being installed. [default: firefox] 12:24:06 INFO - mkdir: /builds/slave/test/build/application 12:24:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:24:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 12:24:38 INFO - Reading from file tmpfile_stdout 12:24:38 INFO - Output received: 12:24:38 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:24:38 INFO - Running post-action listener: _resource_record_post_action 12:24:38 INFO - [mozharness: 2017-01-09 20:24:38.148360Z] Finished install step (success) 12:24:38 INFO - [mozharness: 2017-01-09 20:24:38.148553Z] Running run-tests step. 12:24:38 INFO - Running pre-action listener: _resource_record_pre_action 12:24:38 INFO - Running main action method: run_tests 12:24:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:24:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:24:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:24:38 INFO - 2017-01-09 12:24:38.163 screenresolution[1901:11647] starting screenresolution argv=screenresolution get 12:24:38 INFO - 2017-01-09 12:24:38.165 screenresolution[1901:11647] Display 0: 1600x1200x32@60 12:24:38 INFO - 2017-01-09 12:24:38.171 screenresolution[1902:11649] starting screenresolution argv=screenresolution list 12:24:38 INFO - Available Modes on Display 0 12:24:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:24:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:24:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:24:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:24:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:24:38 INFO - Intel Iris: 12:24:38 INFO - Chipset Model: Intel Iris 12:24:38 INFO - Type: GPU 12:24:38 INFO - Bus: Built-In 12:24:38 INFO - VRAM (Dynamic, Max): 1536 MB 12:24:38 INFO - Vendor: Intel (0x8086) 12:24:38 INFO - Device ID: 0x0a2e 12:24:38 INFO - Revision ID: 0x0009 12:24:38 INFO - Displays: 12:24:38 INFO - Display: 12:24:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:24:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:24:38 INFO - Display Serial Number: 1600x1200 60 12:24:38 INFO - Main Display: Yes 12:24:38 INFO - Mirror: Off 12:24:38 INFO - Online: Yes 12:24:38 INFO - Rotation: Supported 12:24:38 INFO - Return code: 0 12:24:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:24:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:24:38 INFO - Python 2.7.3 12:24:38 INFO - Return code: 0 12:24:38 INFO - grabbing minidump binary from tooltool 12:24: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')]} 12:24:38 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 0x1078f7d30>, '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 0x10775ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078efb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:24:38 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 12:24:38 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 12:24:38 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 12:24:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:24:38 INFO - Return code: 0 12:24:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:24:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:24:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:24:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:24:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:24:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.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 12:24:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.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 12:24:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIlsDNZ1YR/Render', 12:24:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:24:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:24:38 INFO - 'HOME': '/Users/cltbld', 12:24:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:24:38 INFO - 'LOGNAME': 'cltbld', 12:24:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:24:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:38 INFO - 'MOZ_NO_REMOTE': '1', 12:24:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:24:38 INFO - 'NO_EM_RESTART': '1', 12:24:38 INFO - 'PAGER': '/bin/cat', 12:24:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:38 INFO - 'PWD': '/builds/slave/test', 12:24:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:24:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:24:38 INFO - 'SHELL': '/bin/bash', 12:24:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9NUlnMou5F/Listeners', 12:24:38 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 12:24:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:24:38 INFO - 'USER': 'cltbld', 12:24:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:24:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:38 INFO - 'XPC_FLAGS': '0x0', 12:24:38 INFO - 'XPC_SERVICE_NAME': '0', 12:24:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:24:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.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 12:24:38 INFO - mozversion application_buildid: 20170109105308 12:24:38 INFO - mozversion application_changeset: 1f3bb12e7ea66181f264cb8f5f4ab125f6b56fbf 12:24:38 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:24:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:24:38 INFO - mozversion application_name: Firefox 12:24:38 INFO - mozversion application_remotingname: firefox-dev 12:24:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:24:38 INFO - mozversion application_vendor: Mozilla 12:24:38 INFO - mozversion application_version: 52.0a2 12:24:38 INFO - mozversion platform_buildid: 20170109105308 12:24:38 INFO - mozversion platform_changeset: 1f3bb12e7ea66181f264cb8f5f4ab125f6b56fbf 12:24:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:24:38 INFO - mozversion platform_version: 52.0a2 12:24:38 INFO - using testdate: 1483993478 12:24:38 INFO - actual date: 1483993478 12:24:38 INFO - starting webserver on 'localhost:49669' 12:24:38 INFO - SUITE-START | Running 2 tests 12:24:38 INFO - TEST-START | dromaeo_css 12:24:38 INFO - operating with platform_type : mac_ 12:24:38 INFO - Initialising browser for dromaeo_css test... 12:24:38 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmptUQxol/profile http://localhost:49669/getInfo.html) 12:24:41 INFO - PROCESS | 1912 | 2017-01-09 12:24:41.966 plugin-container[1914:11814] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3b, name = 'com.apple.tsm.portname' 12:24:41 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:41 INFO - PROCESS | 1912 | 2017-01-09 12:24:41.969 plugin-container[1914:11814] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 12:24:41 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:41 INFO - PROCESS | 1912 | 2017-01-09 12:24:41.970 plugin-container[1914:11814] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:24:42 INFO - PROCESS | 1912 | __metrics Screen width/height:1600/1200 12:24:42 INFO - PROCESS | 1912 | colorDepth:24 12:24:42 INFO - PROCESS | 1912 | Browser inner width/height: 1024/689 12:24:42 INFO - PROCESS | 1912 | __metrics 12:24:42 ERROR - PROCESS | 1912 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 12:24:42 INFO - TEST-INFO | 1912: exit 0 12:24:42 INFO - Browser initialized. 12:24:42 INFO - Running cycle 1/1 for dromaeo_css test... 12:24:42 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmptUQxol/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:24:45 INFO - PROCESS | 1915 | 2017-01-09 12:24:45.689 plugin-container[1916:11946] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 12:24:45 INFO - PROCESS | 1915 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:45 INFO - PROCESS | 1915 | 2017-01-09 12:24:45.689 plugin-container[1916:11946] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 12:24:45 INFO - PROCESS | 1915 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:45 INFO - PROCESS | 1915 | 2017-01-09 12:24:45.689 plugin-container[1916:11946] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:24:55 INFO - PROCESS | 1915 | RSS: Main: 229851136 12:24:55 INFO - PROCESS | 1915 | 12:27:58 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49669/tests/dromaeo/cssquery-ext.html) 12:28:00 INFO - PROCESS | 1915 | RSS: Main: 235225088 12:28:00 INFO - PROCESS | 1915 | 12:31:02 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-ext.html (next: http://localhost:49669/tests/dromaeo/cssquery-jquery.html) 12:31:03 INFO - PROCESS | 1915 | RSS: Main: 244625408 12:31:03 INFO - PROCESS | 1915 | 12:34:05 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49669/tests/dromaeo/cssquery-mootools.html) 12:34:05 INFO - PROCESS | 1915 | RSS: Main: 247402496 12:34:05 INFO - PROCESS | 1915 | 12:37:07 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49669/tests/dromaeo/cssquery-prototype.html) 12:37:08 INFO - PROCESS | 1915 | RSS: Main: 246714368 12:37:08 INFO - PROCESS | 1915 | 12:40:10 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49669/tests/dromaeo/cssquery-yui.html) 12:40:11 INFO - PROCESS | 1915 | RSS: Main: 250155008 12:40:11 INFO - PROCESS | 1915 | 12:43:14 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-yui.html (next: http://localhost:49669/tests/dromaeo/cssquery-dojo.html) 12:43:14 INFO - PROCESS | 1915 | RSS: Main: 251179008 12:43:14 INFO - PROCESS | 1915 | 12:43:14 INFO - PROCESS | 1915 | __start_tp_report 12:43:14 INFO - PROCESS | 1915 | _x_x_mozilla_page_load 12:43:14 INFO - PROCESS | 1915 | _x_x_mozilla_page_load_details 12:43:14 INFO - PROCESS | 1915 | |i|pagename|runs| 12:43:14 INFO - PROCESS | 1915 | |0;dojo.html;2812.212580477467;3310.619278782939;3319.834008299585;3341.2983273512555;3356.9832150839243;5581.804636837712;5606.327240731108;5635.126555383912;5636.859078523039;5663.235463212465;5601.915971260434;5633.971830140854;5713.885722285552;5724.570657200717;5767.221425107609;5122.487751224876;5152.252923326116;5177.818776342829;5196.610254230929;5197.272381866542;5170.715610641413;5178.896422071556;5218.478152184789;5237.188235823453;5246.658967167141;5024.145895197825;5087.23691446286;5111.463296353885;5208.947910520922;5222.738863056832;5073;5116.616253780944;5118.6416950813455;5137.8201762938115;5148.330717006803;4646.883827904296;5017.5985921126585;5049.394072711261;5075.746212689388;5096.439391666913;5452.182172674091;5565.1095824667855;5608.130739735348;5610.074337734269;5664.178694089334;5912.699206174635;5996.550258730613;6037.607555508878;6128.448439640454;6156.107364432177;6176.197094377747;6182.258129024546;6206.751729930797;6212.875742485125;6235.594686345373;5564.972175139179;5720.513756330759;5740.5981581289325;5741;5741.167530708024;6154.938450615526;6186.381361863778;6214.689265536705;6246.4378205960775;6268.216472940882;6026.367231440636;6035.758569657164;6057.121717350959;6067.7269522871575;6093.542984276197;6140.662263575547;6177.135201071854;6178.165947597106;6204.7828326008375;6213.316535181217;5034.8237811677145;5933.970330148321;5972.790952316648;5985.670788106696;6062.605930614496;6086.786962456381;6130.440204514279;6144.941444616014;6160.322364539897;6176.073588961692;4877.195262781673;4879.243717223765;4885.755712214375;4888.608911287089;4933.9506604933495;4282.336237883133;4289.699721019561;4323.63249123828;4341.08837144197;4390.2317094508335;5442.102053161184;5629.915551266734;5645.068563687028;5683.914741278884;5724.79963201286;5061.772220250097;6144.047672610753;6162.414570615784;6174.320824709278;6202.069689546604;5497.505224529811;5556.444355564411;5562.805301814498;5602.243697100839;5651.660900345993;5255;5289.338832645919;5292.153255479105;5343.732813359316;5370.516653501204;6243.406876346739;6272.372762723691;6280.277768056732;6292.716827742763;6300.495960323255;6035.78874739382;6176.598521096114;6187.81436556904;6213.2233470816145;6228.750849966132;6187.938120618916;6240.93904036324;6267.5299352549655;6271.717772700148;6276.529260305404;6109.816705498843;6112.388761123852;6120.329878791587;6124.6325220486915;6129.233845769279;6108.328083910677;6144.787876364595;6164.951958167212;6165.599236049642;6172.321044685171;5738.598298119258;5799.971000144972;5806.738696758572;5859.736311865891;5871.383504731942;5851.707414629166;5890.293164820249;5900.232969713909;5912.763489460373;5979.790707325223;4705.64707646921;5745.339285981999;5810.767569297181;5828.5919985602295;5837.445442682938;5906.911396328973;5959.702014899324;5985.221921150222;5988.610740301867;6002.789902353396;5630.4369563043365;5702;5702.857428564319;5738.713064346683;5794.739236734273 12:43:14 INFO - PROCESS | 1915 | |1;ext.html;506.7111746304607;514.5317760837638;517.9663321884075;519.974001299935;537.9139337705968;38365.80817095914;39598.802005989965;39907.99999999996;42148;42149.57850421495;40003.59996400035;40477.19045619093;40489.797551012205;40569;40609.39085913714;9296.72109836706;9399.577019034126;9447.338686291961;9462.52687365629;9481.241500679964;9440.339176257665;9536.046395360478;9547.761305967371;9560.760980975461;9566.186874115709;9498.430094194337;9542.713718588453;9547.952260238724;9564.665236716717;9568.56941437637;9465.148136667733;9479.146876781087;9543.90245121813;9549.140577348073;9560.95219523905;7788.610569471503;9397.859032114524;9502.714918552454;9504.572294246705;9505;39435;39527.8023609883;39589.80205098985;39658.80170599157;39686;3302.78531895426;3323.5180898769786;3403.1151900505797;3481.1645205150835;3771.1891943232336;22618.98214580348;24264.878675606684;29507.409851802844;29826.254343641584;31179;2811.845348505831;2928.857745479265;3057.09815604395;3097.9471614610507;3242.3353212591364;428543;442363;479635.60182199214;479759;480944;45364.77317613457;58727.70636146877;58756.7062164695;58823;58862.11706824401;49731.502684972686;62163;62345.75308493805;62552.68723656353;62633;16014.959027663164;16180.352785888432;16191.83808161927;16266.837331626532;16317.428889988794;12331.383430828422;12375.319357435248;12385.566505172275;12386.071044671686;12418.441170147365;14756.926215368854;18578.35057688108;18761.249550017845;18777.34279300218;19092.045397730057;7552.811179720441;7575.431842611826;7696.345810606162;7769.53382797034;7790.298873101448;845.7251393297203;985.383970288727;1001.1690297053428;1007.4056306778889;1054.947252637365;29834.95577654772;29976.800927963075;30158.246043849078;30205.848970755444;30630.846845766075;905.0361365146098;1067.5409573883148;1068.2308737708838;1083.9241253112207;1128.904043156341;13019.349032548334;15058.472953446577;15453.459128930434;15536.533903982898;15805.446809361618;553241;559638;560224;561288;562829;56254.43745562574;56977.14534281989;57023.85952281014;57047;57093.714531427075;1187.5190547828142;1336.7593833109938;1355.6949686320697;1375.8830499407463;1383.280694039094;28803.711962880105;29353;29373.853130735064;29530.852345738993;30127.24681882883;49981;50189;50396.992060157856;50592.74703626458;50620;549823;550691;551250;551585;552763;26045.6093158599;26432.207033789018;26542.734572654026;26856.194314170607;26877.328066798487;45594;45851;46607.766961164976;46630.30054549252;46813.76593117013;33509.49735753917;33620.99137025893;33743.83128084344;33914.66085339214;34338;37455.81272093622;37581.4362784553;38217.235655287295;38279.234415312094;38766.6123338763;25135;31511.212219693774;32125.678743213204;32257;35542.822285888404;31436.528452073693;34315;34328.82835585806;35174.296514069065;36039.099022523595;20233.089510972335;25010.874945625157;25221.369465763502;25285.87357063203;25563.36091597725 12:43:14 INFO - PROCESS | 1915 | |2;jquery.html;337.9355031650302;346.623913054336;373.09524403321956;380.6402949212994;386.81626227541904;15241.771373429407;19219.32732354368;19682.507937301576;19919.701204481928;20164.19343226269;17938.578291930586;19773.703394449094;19809.702854457195;20038.699419508717;20122.49693757657;5341.706206158659;5942.70286485674;5973.313068997056;5985.221921150244;5990.940090599103;5610.663360198401;5824.155497452867;5893.499052580536;5897.764089436439;5904;5349.866253343678;5495.862603434906;5599.468050535193;5841.766329346821;5856.970715146418;5720.599558030939;5814.796482123148;5850.268716410449;5859.2382990211;5882.14708867214;4878.609711223094;5065.214891691794;5155.974220128913;5166.250893620404;5174.456682048369;24244.636330455058;24331.391715207083;24491.26526204217;24540.63189052148;24720.25839224808;2230.542738738555;2245.820334373246;2276.64711969645;2301.171578231835;2377.3699969508093;5660.033942494511;6773.4242589379965;6897.9655101724675;14432.494862679756;14748.115113093249;1838.6138910828608;1841.300305883769;1854.8887066776037;1879.539512819368;1883.8775479593783;118134.99999999914;118411;120006;121408;121863.78136218613;9226.907730922605;10730.73173170677;10743.78512429747;11119.55521779136;11236.044936180353;11486.94256528712;13552.322383880766;13602;13626;13637.113587616774;8223.54770487617;8434.57827108642;8438.987321521457;8545.871811922827;8581.828363432822;8374.790630234293;8431.241188293083;8483.278921291636;8572.742817715478;8590.269827064645;4754.453237877619;6637.46900247979;6651.534392592569;6677.098591690156;6677.76627818024;1587.928543215535;1867.094459187293;1873.9437816865516;1878.1548303263587;1893.6686079936233;937.7327461673391;948.6252930092575;957.5164541906432;986.8470387090013;1008.3193844155165;18585.90707046456;19929.402117936486;20882.477938051365;21747.130114795233;21827.12691492354;930.6417029443713;932.7761337279004;944.7449188719007;995.8000609265818;997.1574019953129;11374.829377559343;11665.650030499099;11675.474603642857;11681.707957300965;11700;545310.2734486302;585621;586585;598990;599377;78806.42387152225;111301;113649.72700545943;119378;119776;907.2968449451596;1000.5747557288123;1015.1422048369117;1015.5379302417382;1019.2050200843354;14636.926815366282;17916.641667166325;18649.813501864806;19163.520911977314;19361.32235371755;36042.85692938395;46546.069078618915;48099.75950120227;48162;48463.75768121137;66877.32806679871;66949;66951;67153.32846671666;67946;6462.773802916875;7801.648925798239;7845.450818442654;8101.270885620323;8115.066767321833;7034;9381.577828997848;12639.304838233986;12706.618801435972;12814.231146131262;10097.5456104474;10492.169242038353;10744.194185435967;10782.784344313226;11425.942870285595;10396.948015259875;10820.89179108199;10916.945415272872;11060.944695276472;11242.213045086766;10041.49792510357;10870.293430926964;11366.431678416211;11566.826497602711;11622.418879056182;7777.261160189773;7871.0554733432355;8063.475874068166;8215.178482151738;8249.29880960125;10896.237263391486;11005.064569511682;11269.154813388865;11274.60538881168;11318.037966772916 12:43:14 INFO - PROCESS | 1915 | |3;mootools.html;1278.750643624493;1458.5624312706188;1597.7603359496081;1762.1893928792754;1792.4891405949306;7501;7775.416843736714;7939.761807145787;8445.32437405007;8454.91545084549;7964.641591128398;8210.58947052648;8239.876401853977;8348.206920342558;8352.958235208815;2296.3455415206713;2319.7332306784683;2320.9767902320928;2324.8140148788143;2329.6720869104615;2312.2138472919205;2348.58899692554;2359.3629719975597;2363.385519764865;2370.8340416170877;2286.325533967484;2291.46150654596;2310.90756369745;2317.1194945920524;2320.0487800002;2270.0919632147106;2279.2706333973133;2302.1366987379733;2308.7575804540534;2314.143766806284;2289.645105008726;2304.469971906471;2308.8499247549025;2316.0388438797877;2320.4662927526765;8217.260446559778;8389.286910612605;8412.453190542596;8412.915870841274;8442.94463192101;5078.542931136179;5205.8958820823755;5226.973865130688;5240.502152295526;5244.239585260116;3267.803931764102;3429.657034296575;3520.77114987525;3587.210813620999;3601.837917293728;1015.2892974917587;1028.3726926574784;1082.4046774274118;1083.4636854756866;1091.5688303120303;10743.623973160902;10790.838137427867;10856.131509479144;10868.239223254375;10870.293430926964;4176.867349487884;4401.801918913657;4487.349334346502;4697.13103075932;4739.95260047402;9335.673251436167;9393.812123757487;9426.151646351862;9437.103475169848;9452.10205030521;5200.141976573823;5204.193350030751;5211.1662134059125;5215.400228973641;5224.791008359699;4299;4325.300063328688;4711.882202944954;4744.122337367598;4782.067496838084;3818.522684664417;3846.653801157909;3913.5108111486065;3931.8427262909736;3952.7430717003303;1009.6264382178495;1070.2615195515225;1126.5155982927256;1249.3275150935617;1280.1870812034426;802.8314054048715;887.3123329419623;905.610587447404;911.366600212859;919.0166521821586;4693.460252070987;5764.538836893039;5801.274840644919;5817.650940943557;5855.882882342329;853.2448782827242;937.3391758810022;960.7357976556364;1014.1683819268277;1031.6853359725205;3725.2549490102087;3759.7180211484247;3836.9808150959066;3858;3881.6312450317173;9828.852567211497;10175.033371829664;10195.235357348229;10202.489875506195;10216.591336346612;10150.746231344276;10163.7459063524;10180.541875615616;10180.898191017995;10188.032136946978;7667.424943129176;7793.688252469951;7925.167857374892;7943.324817390587;7951.801204969922;5458.208559758892;5489.451054894478;5559.777608895599;5576.079946808731;5627.352854421794;9240.630374784932;9362.016988216139;9392.436453812794;9416.246700264099;9436.339456237998;9226.169644732006;9405.670801521914;9465.716028519155;9514.857277140987;9578.952105239428;6681.76613818514;6754.6622668865375;6795.28649491816;6814.4548436124;6857.382835544825;7821.1396746359;7894.447388682737;7904.051513818378;7906.16985216544;7932.6430310635005;6995.650217489207;7051.788446346618;7120.572765634079;7144.928550714426;7259.12890453149;6891.896621550581;7026.367626913602;7028.859422811617;7117.217106118426;7143.749968751069;6712.295209002984;6712.2952090031795;6766.424853887278;6784.964510646688;6787.796366109024;4799.616030717464;4819.373481447389;4825.879353016203;4832.855014349575;4869.342638743724;4787.640926930564;4834;4938.012397520439;4946.579540738933;4948.727819969792 12:43:14 INFO - PROCESS | 1915 | |4;prototype.html;300.7203300930134;303.55680706168994;310.01879052797904;311.8783674366999;314.90238026211875;5181.637285390024;5211.088059589571;5276.103062479378;5498.505134537891;5511.641743286688;4731.793392684861;5157.355330583677;5360.775278259527;5401.810936617219;5466.371367292762;3559.8608445297514;4051.817668204923;4090.4456306603447;4121.372058037074;4147.8963025924295;3818.885433437001;4080.3879418087226;4111.917761644765;4146.9792651036705;4162.562930892258;3554.9111272218142;3731.2724018816343;4024.5774193709676;4039.939400908989;4061.5630621627083;3436.226848958989;3889.8444062237477;4047.453593764863;4072.775997320161;4075.3071977763702;3261;3522.330757156132;3551.1122219445137;3554.697850682695;3568.3041806847414;4897.828575999859;5180.6373553851245;5222;5251.560228050633;5295.0998330283555;3792.6965842732516;4558.566936141061;4567.447067996897;4603.861884143481;4643.512431194709;5622.550195984352;5623.718814059281;5639.774409023634;5661.858453538694;5741.799037033684;3685.539307586552;3832.9808350958347;3850.191459793447;3850.903727406809;3853.325668008087;5633.971830140823;5860.355360910296;5887.322957859858;5891.823245302648;5962.869493515939;3442.8106454144763;3631.291898079849;3657.195417008254;3914.549826769901;3941.428492868575;3098.5352197170605;3338.015285490786;3778.2254637799183;3981;3994.1227623017135;3569.8929032129076;4173.64524015456;4581.65637577183;4611.754826196908;4617.492075871652;5690.374058853523;5720.141978703145;5737.3975732548315;5749.655020698771;5755.740991655385;1045.5660900726114;1196.7367179220557;1231.4766224354792;1247.7878760610718;1261.0164072023836;756.8637645223913;845.8012367093728;870.1429092344036;872.9655358400316;875.2297977779514;4519.796609152596;4840.661153719206;4956.654258375582;5004.699718016858;5036;4897.559219670246;5486.202036961099;5486.61593688438;5552.41699621542;5586.804461843816;4008.6392224699916;4793.808247670124;4824.372831531949;4988.55103040728;5045.5459008689395;2661.9068332608267;2863.985680071586;2876.9712302876706;2920.3575213453432;2926.5610158475974;5684.715764211773;5685.3177618685195;5697.772089116389;5739.887020323342;5796.275465566804;5150.24032456309;5193.766280517386;5573.442655734475;5685.536853569928;5748.770049198069;5615.15361977047;5621.550275977912;5634.746436410372;5653.575981801299;5659.603827732101;4500.774961251859;5472.425395333592;5508.779648814163;5565.554755619622;5573;4826.356101143614;5131.409263128442;5396.571542805675;5404.2163886235485;5510.366307874646;5187.6682969719195;5302.681839089667;5591.6365436246515;5627.803026894039;5640.492355687844;3120.60992375953;3415.565462505704;3516.8394429838604;3704.4258139988347;3713.20166164276;4729.408823897013;5446.183072539151;5612.979327442036;5804.767809287581;5961.7615295387695;5618.297712785902;5684.459976302244;5704.401037890962;5706.229658996149;5730.283714535683;5748.166515855094;5789.56578256624;5791.683326669366;5792.94207057924;5814.389489103583;4646.652470783435;5231.885634064495;5464.453554644663;5604.607677462702;5704.315482142004;5445.782168713207;5645.70148865755;5706.9714651426475;5738.626989245686;5806.796762113306;4354.477462704495;4521.1409832131785;5563.193336966036;5577.3028371453565;5615.326160860723 12:43:14 INFO - PROCESS | 1915 | |5;yui.html;638.4796390941383;710.875596770565;717.6053170756082;764.1479750078664;779.3299542588701;11533.826992595097;13972.231527265996;14684.559463216106;14690.559283221493;14782.482613108543;11814.113941454383;13196.802047969288;13350.66623334417;14097.506587269421;14875.107493550395;811.5049819610033;842.751388340441;845.2012847858776;845.564534264851;846.1158089796147;823.086374124721;831.9500829950192;832.9000519937586;835.9916400836005;837.5979529825687;882.3338379523451;885.3315746611331;887.4852585500395;889.5374405309235;889.7953470701742;863.8272345530919;879.6393478673713;880.2078129683305;883.2227639677108;884.6903583745702;650.5576208178437;656.2551504042946;656.2649832187932;656.7734131724548;658.1246941567704;13833.170009799343;13899.583012509642;13901.652458688613;13928.094673846268;13942.721145577032;12985.35073246334;13295.667608309774;13412.195268283935;13483.325833708275;13500.797488037688;11004.229703920728;11183.769785323608;11338.943305283421;11385.761828248533;11426.37154956477;3556.590992035923;3637.290728307981;3683.723720720957;3733.9253214935547;3798.9430158547643;83746.99999999939;83992.58003709943;86031.56984215102;86591;87194;10531.94734026325;10590.152787776959;10668.786624267626;10694.893051069545;10719.249652524404;17132.314707411562;20985.95070246512;21010.579788404146;21097.78902210989;21339.25312614051;7581.50720203185;7610.7822748359995;7658.310752032344;7660.080790305199;7837.981062461843;3817.1602247505502;4090.141070375195;4237.660987121023;4252.638525725348;4258.914821703549;3366.3940490711416;3437.2781715839956;3486.163320802989;3494;3497.89506314811;484.36064394998965;526.2211926349025;526.276369991262;540.2112915143855;559.680981840347;603.3182503770711;612.9417705317987;615.2033117113349;644.4135836388864;665.8867992441296;7766.961165194138;8825.558722063999;9277.025912279249;9793.167580755715;9826.4104153751;558.6145559563888;562.7748900439857;589.6727316339387;667.5093806052645;668.2916108924555;4214.3889136075095;4241.745495270294;4443.7111587746695;4465.196264672325;4470.932936005963;107301.4634926836;110125;115502.4224878887;116207;120807.39596301962;75832.1041973955;88670;89055;94998;104312;54042.37872863821;58883;59095;81795;83640.16359836445;9328.207102396373;9598.08937564952;9627.037296270317;9673.123666480067;9726.70819875405;39075.48320651155;41125.588744112174;46848.765756171;49140.75429622829;50262.246066308304;33248.50127248045;50246;51103.46689599318;58873.70563147157;66926.8868042403;11963.222390544586;12160.148789584644;12197.75604487923;12543;12754;12915.806262906252;13075.215487070805;14046.227457489937;14160.504382346568;14363.56909292723;12658.87341126577;12841.614751557468;13038.348082596021;13148.079634425498;13165.802512962506;12550.1842380245;12939.288339141438;13024.153430027018;13153.145045572008;13465.259410732504;12630.421197350332;12922.095453318178;12927.935360323138;13020.28388438607;13048.673783155496;9682.559504483073;11031.448427578429;11081.224314297922;11119.943605357477;11610.303381797119;10749.57199215958;11286.887131128911;11476.770464590829;11480.598179063692;11541.076713862703 12:43:14 INFO - PROCESS | 1915 | __end_tp_report 12:43:14 INFO - PROCESS | 1915 | __start_cc_report 12:43:14 INFO - PROCESS | 1915 | _x_x_mozilla_cycle_collect,375 12:43:14 INFO - PROCESS | 1915 | __end_cc_report 12:43:14 INFO - PROCESS | 1915 | __startTimestamp1483994594401__endTimestamp 12:43:14 INFO - PROCESS | 1915 | 12:43:14 INFO - PROCESS | 1915 | ------- Summary: start ------- 12:43:14 INFO - PROCESS | 1915 | Number of tests: 6 12:43:14 INFO - PROCESS | 1915 | 12:43:14 INFO - PROCESS | 1915 | [#0] dojo.html Cycles:165 Average:5627.33 Median:5745.34 stddev:646.01 (11.2%) stddev-sans-first:609.08 12:43:14 INFO - PROCESS | 1915 | Values: 2812.2 3310.6 3319.8 3341.3 3357.0 5581.8 5606.3 5635.1 5636.9 5663.2 5601.9 5634.0 5713.9 5724.6 5767.2 5122.5 5152.3 5177.8 5196.6 5197.3 5170.7 5178.9 5218.5 5237.2 5246.7 5024.1 5087.2 5111.5 5208.9 5222.7 5073.0 5116.6 5118.6 5137.8 5148.3 4646.9 5017.6 5049.4 5075.7 5096.4 5452.2 5565.1 5608.1 5610.1 5664.2 5912.7 5996.6 6037.6 6128.4 6156.1 6176.2 6182.3 6206.8 6212.9 6235.6 5565.0 5720.5 5740.6 5741.0 5741.2 6154.9 6186.4 6214.7 6246.4 6268.2 6026.4 6035.8 6057.1 6067.7 6093.5 6140.7 6177.1 6178.2 6204.8 6213.3 5034.8 5934.0 5972.8 5985.7 6062.6 6086.8 6130.4 6144.9 6160.3 6176.1 4877.2 4879.2 4885.8 4888.6 4934.0 4282.3 4289.7 4323.6 4341.1 4390.2 5442.1 5629.9 5645.1 5683.9 5724.8 5061.8 6144.0 6162.4 6174.3 6202.1 5497.5 5556.4 5562.8 5602.2 5651.7 5255.0 5289.3 5292.2 5343.7 5370.5 6243.4 6272.4 6280.3 6292.7 6300.5 6035.8 6176.6 6187.8 6213.2 6228.8 6187.9 6240.9 6267.5 6271.7 6276.5 6109.8 6112.4 6120.3 6124.6 6129.2 6108.3 6144.8 6165.0 6165.6 6172.3 5738.6 5800.0 5806.7 5859.7 5871.4 5851.7 5890.3 5900.2 5912.8 5979.8 4705.6 5745.3 5810.8 5828.6 5837.4 5906.9 5959.7 5985.2 5988.6 6002.8 5630.4 5702.0 5702.9 5738.7 5794.7 12:43:14 INFO - PROCESS | 1915 | 12:43:14 INFO - PROCESS | 1915 | [#1] ext.html Cycles:180 Average:65693.52 Median:39594.30 stddev:140359.22 (354.5%) stddev-sans-first:140667.63 12:43:14 INFO - PROCESS | 1915 | Values: 506.7 514.5 518.0 520.0 537.9 38365.8 39598.8 39908.0 42148.0 42149.6 40003.6 40477.2 40489.8 40569.0 40609.4 9296.7 9399.6 9447.3 9462.5 9481.2 9440.3 9536.0 9547.8 9560.8 9566.2 9498.4 9542.7 9548.0 9564.7 9568.6 9465.1 9479.1 9543.9 9549.1 9561.0 7788.6 9397.9 9502.7 9504.6 9505.0 39435.0 39527.8 39589.8 39658.8 39686.0 3302.8 3323.5 3403.1 3481.2 3771.2 22619.0 24264.9 29507.4 29826.3 31179.0 2811.8 2928.9 3057.1 3097.9 3242.3 428543.0 442363.0 479635.6 479759.0 480944.0 45364.8 58727.7 58756.7 58823.0 58862.1 49731.5 62163.0 62345.8 62552.7 62633.0 16015.0 16180.4 16191.8 16266.8 16317.4 12331.4 12375.3 12385.6 12386.1 12418.4 14756.9 18578.4 18761.2 18777.3 19092.0 7552.8 7575.4 7696.3 7769.5 7790.3 845.7 985.4 1001.2 1007.4 1054.9 29835.0 29976.8 30158.2 30205.8 30630.8 905.0 1067.5 1068.2 1083.9 1128.9 13019.3 15058.5 15453.5 15536.5 15805.4 553241.0 559638.0 560224.0 561288.0 562829.0 56254.4 56977.1 57023.9 57047.0 57093.7 1187.5 1336.8 1355.7 1375.9 1383.3 28803.7 29353.0 29373.9 29530.9 30127.2 49981.0 50189.0 50397.0 50592.7 50620.0 549823.0 550691.0 551250.0 551585.0 552763.0 26045.6 26432.2 26542.7 26856.2 26877.3 45594.0 45851.0 46607.8 46630.3 46813.8 33509.5 33621.0 33743.8 33914.7 34338.0 37455.8 37581.4 38217.2 38279.2 38766.6 25135.0 31511.2 32125.7 32257.0 35542.8 31436.5 34315.0 34328.8 35174.3 36039.1 20233.1 25010.9 25221.4 25285.9 25563.4 12:43:14 INFO - PROCESS | 1915 | 12:43:14 INFO - PROCESS | 1915 | [#2] jquery.html Cycles:180 Average:33388.96 Median:24515.95 stddev:97034.72 (395.8%) stddev-sans-first:97275.19 12:43:14 INFO - PROCESS | 1915 | Values: 337.9 346.6 373.1 380.6 386.8 15241.8 19219.3 19682.5 19919.7 20164.2 17938.6 19773.7 19809.7 20038.7 20122.5 5341.7 5942.7 5973.3 5985.2 5990.9 5610.7 5824.2 5893.5 5897.8 5904.0 5349.9 5495.9 5599.5 5841.8 5857.0 5720.6 5814.8 5850.3 5859.2 5882.1 4878.6 5065.2 5156.0 5166.3 5174.5 24244.6 24331.4 24491.3 24540.6 24720.3 2230.5 2245.8 2276.6 2301.2 2377.4 5660.0 6773.4 6898.0 14432.5 14748.1 1838.6 1841.3 1854.9 1879.5 1883.9 118135.0 118411.0 120006.0 121408.0 121863.8 9226.9 10730.7 10743.8 11119.6 11236.0 11486.9 13552.3 13602.0 13626.0 13637.1 8223.5 8434.6 8439.0 8545.9 8581.8 8374.8 8431.2 8483.3 8572.7 8590.3 4754.5 6637.5 6651.5 6677.1 6677.8 1587.9 1867.1 1873.9 1878.2 1893.7 937.7 948.6 957.5 986.8 1008.3 18585.9 19929.4 20882.5 21747.1 21827.1 930.6 932.8 944.7 995.8 997.2 11374.8 11665.7 11675.5 11681.7 11700.0 545310.3 585621.0 586585.0 598990.0 599377.0 78806.4 111301.0 113649.7 119378.0 119776.0 907.3 1000.6 1015.1 1015.5 1019.2 14636.9 17916.6 18649.8 19163.5 19361.3 36042.9 46546.1 48099.8 48162.0 48463.8 66877.3 66949.0 66951.0 67153.3 67946.0 6462.8 7801.6 7845.5 8101.3 8115.1 7034.0 9381.6 12639.3 12706.6 12814.2 10097.5 10492.2 10744.2 10782.8 11425.9 10396.9 10820.9 10916.9 11060.9 11242.2 10041.5 10870.3 11366.4 11566.8 11622.4 7777.3 7871.1 8063.5 8215.2 8249.3 10896.2 11005.1 11269.2 11274.6 11318.0 12:43:14 INFO - PROCESS | 1915 | 12:43:14 INFO - PROCESS | 1915 | [#3] mootools.html Cycles:180 Average:5447.89 Median:4947.65 stddev:2982.32 (60.3%) stddev-sans-first:2974.22 12:43:14 INFO - PROCESS | 1915 | Values: 1278.8 1458.6 1597.8 1762.2 1792.5 7501.0 7775.4 7939.8 8445.3 8454.9 7964.6 8210.6 8239.9 8348.2 8353.0 2296.3 2319.7 2321.0 2324.8 2329.7 2312.2 2348.6 2359.4 2363.4 2370.8 2286.3 2291.5 2310.9 2317.1 2320.0 2270.1 2279.3 2302.1 2308.8 2314.1 2289.6 2304.5 2308.8 2316.0 2320.5 8217.3 8389.3 8412.5 8412.9 8442.9 5078.5 5205.9 5227.0 5240.5 5244.2 3267.8 3429.7 3520.8 3587.2 3601.8 1015.3 1028.4 1082.4 1083.5 1091.6 10743.6 10790.8 10856.1 10868.2 10870.3 4176.9 4401.8 4487.3 4697.1 4740.0 9335.7 9393.8 9426.2 9437.1 9452.1 5200.1 5204.2 5211.2 5215.4 5224.8 4299.0 4325.3 4711.9 4744.1 4782.1 3818.5 3846.7 3913.5 3931.8 3952.7 1009.6 1070.3 1126.5 1249.3 1280.2 802.8 887.3 905.6 911.4 919.0 4693.5 5764.5 5801.3 5817.7 5855.9 853.2 937.3 960.7 1014.2 1031.7 3725.3 3759.7 3837.0 3858.0 3881.6 9828.9 10175.0 10195.2 10202.5 10216.6 10150.7 10163.7 10180.5 10180.9 10188.0 7667.4 7793.7 7925.2 7943.3 7951.8 5458.2 5489.5 5559.8 5576.1 5627.4 9240.6 9362.0 9392.4 9416.2 9436.3 9226.2 9405.7 9465.7 9514.9 9579.0 6681.8 6754.7 6795.3 6814.5 6857.4 7821.1 7894.4 7904.1 7906.2 7932.6 6995.7 7051.8 7120.6 7144.9 7259.1 6891.9 7026.4 7028.9 7117.2 7143.7 6712.3 6712.3 6766.4 6785.0 6787.8 4799.6 4819.4 4825.9 4832.9 4869.3 4787.6 4834.0 4938.0 4946.6 4948.7 12:43:14 INFO - PROCESS | 1915 | 12:43:14 INFO - PROCESS | 1915 | [#4] prototype.html Cycles:180 Average:4497.30 Median:5153.80 stddev:1413.10 (27.4%) stddev-sans-first:1381.52 12:43:14 INFO - PROCESS | 1915 | Values: 300.7 303.6 310.0 311.9 314.9 5181.6 5211.1 5276.1 5498.5 5511.6 4731.8 5157.4 5360.8 5401.8 5466.4 3559.9 4051.8 4090.4 4121.4 4147.9 3818.9 4080.4 4111.9 4147.0 4162.6 3554.9 3731.3 4024.6 4039.9 4061.6 3436.2 3889.8 4047.5 4072.8 4075.3 3261.0 3522.3 3551.1 3554.7 3568.3 4897.8 5180.6 5222.0 5251.6 5295.1 3792.7 4558.6 4567.4 4603.9 4643.5 5622.6 5623.7 5639.8 5661.9 5741.8 3685.5 3833.0 3850.2 3850.9 3853.3 5634.0 5860.4 5887.3 5891.8 5962.9 3442.8 3631.3 3657.2 3914.5 3941.4 3098.5 3338.0 3778.2 3981.0 3994.1 3569.9 4173.6 4581.7 4611.8 4617.5 5690.4 5720.1 5737.4 5749.7 5755.7 1045.6 1196.7 1231.5 1247.8 1261.0 756.9 845.8 870.1 873.0 875.2 4519.8 4840.7 4956.7 5004.7 5036.0 4897.6 5486.2 5486.6 5552.4 5586.8 4008.6 4793.8 4824.4 4988.6 5045.5 2661.9 2864.0 2877.0 2920.4 2926.6 5684.7 5685.3 5697.8 5739.9 5796.3 5150.2 5193.8 5573.4 5685.5 5748.8 5615.2 5621.6 5634.7 5653.6 5659.6 4500.8 5472.4 5508.8 5565.6 5573.0 4826.4 5131.4 5396.6 5404.2 5510.4 5187.7 5302.7 5591.6 5627.8 5640.5 3120.6 3415.6 3516.8 3704.4 3713.2 4729.4 5446.2 5613.0 5804.8 5961.8 5618.3 5684.5 5704.4 5706.2 5730.3 5748.2 5789.6 5791.7 5792.9 5814.4 4646.7 5231.9 5464.5 5604.6 5704.3 5445.8 5645.7 5707.0 5738.6 5806.8 4354.5 4521.1 5563.2 5577.3 5615.3 12:43:14 INFO - PROCESS | 1915 | 12:43:14 INFO - PROCESS | 1915 | [#5] yui.html Cycles:180 Average:19097.81 Median:4226.02 stddev:27921.01 (660.7%) stddev-sans-first:27964.93 12:43:14 INFO - PROCESS | 1915 | Values: 638.5 710.9 717.6 764.1 779.3 11533.8 13972.2 14684.6 14690.6 14782.5 11814.1 13196.8 13350.7 14097.5 14875.1 811.5 842.8 845.2 845.6 846.1 823.1 832.0 832.9 836.0 837.6 882.3 885.3 887.5 889.5 889.8 863.8 879.6 880.2 883.2 884.7 650.6 656.3 656.3 656.8 658.1 13833.2 13899.6 13901.7 13928.1 13942.7 12985.4 13295.7 13412.2 13483.3 13500.8 11004.2 11183.8 11338.9 11385.8 11426.4 3556.6 3637.3 3683.7 3733.9 3798.9 83747.0 83992.6 86031.6 86591.0 87194.0 10531.9 10590.2 10668.8 10694.9 10719.2 17132.3 20986.0 21010.6 21097.8 21339.3 7581.5 7610.8 7658.3 7660.1 7838.0 3817.2 4090.1 4237.7 4252.6 4258.9 3366.4 3437.3 3486.2 3494.0 3497.9 484.4 526.2 526.3 540.2 559.7 603.3 612.9 615.2 644.4 665.9 7767.0 8825.6 9277.0 9793.2 9826.4 558.6 562.8 589.7 667.5 668.3 4214.4 4241.7 4443.7 4465.2 4470.9 107301.5 110125.0 115502.4 116207.0 120807.4 75832.1 88670.0 89055.0 94998.0 104312.0 54042.4 58883.0 59095.0 81795.0 83640.2 9328.2 9598.1 9627.0 9673.1 9726.7 39075.5 41125.6 46848.8 49140.8 50262.2 33248.5 50246.0 51103.5 58873.7 66926.9 11963.2 12160.1 12197.8 12543.0 12754.0 12915.8 13075.2 14046.2 14160.5 14363.6 12658.9 12841.6 13038.3 13148.1 13165.8 12550.2 12939.3 13024.2 13153.1 13465.3 12630.4 12922.1 12927.9 13020.3 13048.7 9682.6 11031.4 11081.2 11119.9 11610.3 10749.6 11286.9 11476.8 11480.6 11541.1 12:43:14 INFO - PROCESS | 1915 | -------- Summary: end -------- 12:43:14 INFO - PROCESS | 1915 | 12:43:15 INFO - TEST-INFO | 1915: exit 0 12:43:15 INFO - TEST-OK | dromaeo_css | took 1116294ms 12:43:15 INFO - TEST-START | kraken 12:43:15 INFO - operating with platform_type : mac_ 12:43:15 INFO - Initialising browser for kraken test... 12:43:15 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpSaNHIa/profile http://localhost:49669/getInfo.html) 12:43:18 INFO - PROCESS | 1922 | 2017-01-09 12:43:18.132 plugin-container[1923:13992] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 12:43:18 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:43:18 INFO - PROCESS | 1922 | 2017-01-09 12:43:18.135 plugin-container[1923:13992] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e03, name = 'com.apple.CFPasteboardClient' 12:43:18 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:43:18 INFO - PROCESS | 1922 | 2017-01-09 12:43:18.136 plugin-container[1923:13992] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:43:18 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 12:43:18 INFO - PROCESS | 1922 | colorDepth:24 12:43:18 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 12:43:18 INFO - PROCESS | 1922 | __metrics 12:43:18 ERROR - PROCESS | 1922 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 12:43:19 INFO - TEST-INFO | 1922: exit 0 12:43:19 INFO - Browser initialized. 12:43:19 INFO - Running cycle 1/1 for kraken test... 12:43:19 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpSaNHIa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:43:21 INFO - PROCESS | 1924 | 2017-01-09 12:43:21.808 plugin-container[1925:14125] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 12:43:21 INFO - PROCESS | 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:43:21 INFO - PROCESS | 1924 | 2017-01-09 12:43:21.809 plugin-container[1925:14125] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 12:43:21 INFO - PROCESS | 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:43:21 INFO - PROCESS | 1924 | 2017-01-09 12:43:21.809 plugin-container[1925:14125] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:43:31 INFO - PROCESS | 1924 | RSS: Main: 229539840 12:43:31 INFO - PROCESS | 1924 | 12:43:34 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-beat-detection) 12:43:35 INFO - PROCESS | 1924 | RSS: Main: 241729536 12:43:35 INFO - PROCESS | 1924 | 12:43:37 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-dft) 12:43:38 INFO - PROCESS | 1924 | RSS: Main: 249409536 12:43:38 INFO - PROCESS | 1924 | 12:43:41 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-fft) 12:43:42 INFO - PROCESS | 1924 | RSS: Main: 253161472 12:43:42 INFO - PROCESS | 1924 | 12:43:44 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-oscillator) 12:43:44 INFO - PROCESS | 1924 | RSS: Main: 253534208 12:43:44 INFO - PROCESS | 1924 | 12:43:46 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49669/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:43:47 INFO - PROCESS | 1924 | RSS: Main: 254722048 12:43:47 INFO - PROCESS | 1924 | 12:43:50 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49669/tests/kraken/driver.html?testName=imaging-darkroom) 12:43:51 INFO - PROCESS | 1924 | RSS: Main: 259178496 12:43:51 INFO - PROCESS | 1924 | 12:43:53 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49669/tests/kraken/driver.html?testName=imaging-desaturate) 12:43:54 INFO - PROCESS | 1924 | RSS: Main: 258818048 12:43:54 INFO - PROCESS | 1924 | 12:43:57 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49669/tests/kraken/driver.html?testName=json-parse-financial) 12:43:58 INFO - PROCESS | 1924 | RSS: Main: 258531328 12:43:58 INFO - PROCESS | 1924 | 12:43:59 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49669/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:44:00 INFO - PROCESS | 1924 | RSS: Main: 257318912 12:44:00 INFO - PROCESS | 1924 | 12:44:02 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:44:02 INFO - PROCESS | 1924 | RSS: Main: 257380352 12:44:02 INFO - PROCESS | 1924 | 12:44:04 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:44:05 INFO - PROCESS | 1924 | RSS: Main: 257343488 12:44:05 INFO - PROCESS | 1924 | 12:44:08 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:44:09 INFO - PROCESS | 1924 | RSS: Main: 257351680 12:44:09 INFO - PROCESS | 1924 | 12:44:12 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:44:12 INFO - PROCESS | 1924 | RSS: Main: 257699840 12:44:12 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49669/tests/kraken/driver.html?testName=ai-astar) 12:44:14 INFO - PROCESS | 1924 | RSS: Main: 259674112 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | __start_tp_report 12:44:14 INFO - PROCESS | 1924 | _x_x_mozilla_page_load 12:44:14 INFO - PROCESS | 1924 | _x_x_mozilla_page_load_details 12:44:14 INFO - PROCESS | 1924 | |i|pagename|runs| 12:44:14 INFO - PROCESS | 1924 | |0;ai-astar;106;115;117;118;125;118;116;116;116;98 12:44:14 INFO - PROCESS | 1924 | |1;audio-beat-detection;155;115;113;146;113;112;115;114;139;97 12:44:14 INFO - PROCESS | 1924 | |2;audio-dft;197;200;197;202;198;196;196;195;194;194 12:44:14 INFO - PROCESS | 1924 | |3;audio-fft;81;73;61;85;62;59;59;58;82;59 12:44:14 INFO - PROCESS | 1924 | |4;audio-oscillator;79;72;90;72;69;102;72;92;100;124 12:44:14 INFO - PROCESS | 1924 | |5;imaging-gaussian-blur;135;123;89;88;135;86;99;87;89;87 12:44:14 INFO - PROCESS | 1924 | |6;imaging-darkroom;94;96;96;95;96;95;96;121;151;95 12:44:14 INFO - PROCESS | 1924 | |7;imaging-desaturate;133;129;88;97;88;89;91;98;130;88 12:44:14 INFO - PROCESS | 1924 | |8;json-parse-financial;58;56;56;60;60;62;60;60;61;60 12:44:14 INFO - PROCESS | 1924 | |9;json-stringify-tinderbox;70;71;71;76;72;69;69;72;71;72 12:44:14 INFO - PROCESS | 1924 | |10;stanford-crypto-aes;84;84;84;88;83;83;87;87;85;89 12:44:14 INFO - PROCESS | 1924 | |11;stanford-crypto-ccm;144;140;138;171;130;145;145;154;214;158 12:44:14 INFO - PROCESS | 1924 | |12;stanford-crypto-pbkdf2;167;168;168;169;171;168;168;169;171;166 12:44:14 INFO - PROCESS | 1924 | |13;stanford-crypto-sha256-iterative;69;66;67;68;67;68;67;68;71;67 12:44:14 INFO - PROCESS | 1924 | __end_tp_report 12:44:14 INFO - PROCESS | 1924 | __start_cc_report 12:44:14 INFO - PROCESS | 1924 | _x_x_mozilla_cycle_collect,977 12:44:14 INFO - PROCESS | 1924 | __end_cc_report 12:44:14 INFO - PROCESS | 1924 | __startTimestamp1483994654728__endTimestamp 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | ------- Summary: start ------- 12:44:14 INFO - PROCESS | 1924 | Number of tests: 14 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | [#0] ai-astar Cycles:10 Average:114.50 Median:117.50 stddev:7.40 (6.3%) stddev-sans-first:7.18 12:44:14 INFO - PROCESS | 1924 | Values: 106.0 115.0 117.0 118.0 125.0 118.0 116.0 116.0 116.0 98.0 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | [#1] audio-beat-detection Cycles:10 Average:121.90 Median:127.00 stddev:18.27 (14.4%) stddev-sans-first:14.94 12:44:14 INFO - PROCESS | 1924 | Values: 155.0 115.0 113.0 146.0 113.0 112.0 115.0 114.0 139.0 97.0 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | [#2] audio-dft Cycles:10 Average:196.90 Median:197.00 stddev:2.56 (1.3%) stddev-sans-first:2.71 12:44:14 INFO - PROCESS | 1924 | Values: 197.0 200.0 197.0 202.0 198.0 196.0 196.0 195.0 194.0 194.0 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | [#3] audio-fft Cycles:10 Average:67.90 Median:67.50 stddev:11.09 (16.4%) stddev-sans-first:10.70 12:44:14 INFO - PROCESS | 1924 | Values: 81.0 73.0 61.0 85.0 62.0 59.0 59.0 58.0 82.0 59.0 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | [#4] audio-oscillator Cycles:10 Average:87.20 Median:72.00 stddev:17.83 (24.8%) stddev-sans-first:18.66 12:44:14 INFO - PROCESS | 1924 | Values: 79.0 72.0 90.0 72.0 69.0 102.0 72.0 92.0 100.0 124.0 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | [#5] imaging-gaussian-blur Cycles:10 Average:101.80 Median:87.50 stddev:20.73 (23.7%) stddev-sans-first:18.18 12:44:14 INFO - PROCESS | 1924 | Values: 135.0 123.0 89.0 88.0 135.0 86.0 99.0 87.0 89.0 87.0 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | [#6] imaging-darkroom Cycles:10 Average:103.50 Median:95.50 stddev:18.54 (19.4%) stddev-sans-first:19.35 12:44:14 INFO - PROCESS | 1924 | Values: 94.0 96.0 96.0 95.0 96.0 95.0 96.0 121.0 151.0 95.0 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | [#7] imaging-desaturate Cycles:10 Average:103.10 Median:88.50 stddev:19.38 (21.9%) stddev-sans-first:17.28 12:44:14 INFO - PROCESS | 1924 | Values: 133.0 129.0 88.0 97.0 88.0 89.0 91.0 98.0 130.0 88.0 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | [#8] json-parse-financial Cycles:10 Average:59.30 Median:60.00 stddev:2.00 (3.3%) stddev-sans-first:2.07 12:44:14 INFO - PROCESS | 1924 | Values: 58.0 56.0 56.0 60.0 60.0 62.0 60.0 60.0 61.0 60.0 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.50 stddev:2.00 (2.8%) stddev-sans-first:2.07 12:44:14 INFO - PROCESS | 1924 | Values: 70.0 71.0 71.0 76.0 72.0 69.0 69.0 72.0 71.0 72.0 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:86.00 stddev:2.17 (2.5%) stddev-sans-first:2.24 12:44:14 INFO - PROCESS | 1924 | Values: 84.0 84.0 84.0 88.0 83.0 83.0 87.0 87.0 85.0 89.0 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | [#11] stanford-crypto-ccm Cycles:10 Average:153.90 Median:149.50 stddev:24.03 (16.1%) stddev-sans-first:25.21 12:44:14 INFO - PROCESS | 1924 | Values: 144.0 140.0 138.0 171.0 130.0 145.0 145.0 154.0 214.0 158.0 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.50 Median:168.50 stddev:1.58 (0.9%) stddev-sans-first:1.58 12:44:14 INFO - PROCESS | 1924 | Values: 167.0 168.0 168.0 169.0 171.0 168.0 168.0 169.0 171.0 166.0 12:44:14 INFO - PROCESS | 1924 | 12:44:14 INFO - PROCESS | 1924 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.40 (2.1%) stddev-sans-first:1.41 12:44:14 INFO - PROCESS | 1924 | Values: 69.0 66.0 67.0 68.0 67.0 68.0 67.0 68.0 71.0 67.0 12:44:14 INFO - PROCESS | 1924 | -------- Summary: end -------- 12:44:14 INFO - PROCESS | 1924 | 12:44:15 INFO - TEST-INFO | 1924: exit 0 12:44:15 INFO - TEST-OK | kraken | took 60730ms 12:44:15 INFO - SUITE-END | took 1177s 12:44:15 INFO - Completed test suite (00:19:37) 12:44:15 INFO - javascript benchmark 12:44:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7094.7114666970565, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2812.212580477467, 3310.619278782939, 3319.834008299585, 3341.2983273512555, 3356.9832150839243, 5581.804636837712, 5606.327240731108, 5635.126555383912, 5636.859078523039, 5663.235463212465, 5601.915971260434, 5633.971830140854, 5713.885722285552, 5724.570657200717, 5767.221425107609, 5122.487751224876, 5152.252923326116, 5177.818776342829, 5196.610254230929, 5197.272381866542, 5170.715610641413, 5178.896422071556, 5218.478152184789, 5237.188235823453, 5246.658967167141, 5024.145895197825, 5087.23691446286, 5111.463296353885, 5208.947910520922, 5222.738863056832, 5073.0, 5116.616253780944, 5118.6416950813455, 5137.8201762938115, 5148.330717006803, 4646.883827904296, 5017.5985921126585, 5049.394072711261, 5075.746212689388, 5096.439391666913, 5452.182172674091, 5565.1095824667855, 5608.130739735348, 5610.074337734269, 5664.178694089334, 5912.699206174635, 5996.550258730613, 6037.607555508878, 6128.448439640454, 6156.107364432177, 6176.197094377747, 6182.258129024546, 6206.751729930797, 6212.875742485125, 6235.594686345373, 5564.972175139179, 5720.513756330759, 5740.5981581289325, 5741.0, 5741.167530708024, 6154.938450615526, 6186.381361863778, 6214.689265536705, 6246.4378205960775, 6268.216472940882, 6026.367231440636, 6035.758569657164, 6057.121717350959, 6067.7269522871575, 6093.542984276197, 6140.662263575547, 6177.135201071854, 6178.165947597106, 6204.7828326008375, 6213.316535181217, 5034.8237811677145, 5933.970330148321, 5972.790952316648, 5985.670788106696, 6062.605930614496, 6086.786962456381, 6130.440204514279, 6144.941444616014, 6160.322364539897, 6176.073588961692, 4877.195262781673, 4879.243717223765, 4885.755712214375, 4888.608911287089, 4933.9506604933495, 4282.336237883133, 4289.699721019561, 4323.63249123828, 4341.08837144197, 4390.2317094508335, 5442.102053161184, 5629.915551266734, 5645.068563687028, 5683.914741278884, 5724.79963201286, 5061.772220250097, 6144.047672610753, 6162.414570615784, 6174.320824709278, 6202.069689546604, 5497.505224529811, 5556.444355564411, 5562.805301814498, 5602.243697100839, 5651.660900345993, 5255.0, 5289.338832645919, 5292.153255479105, 5343.732813359316, 5370.516653501204, 6243.406876346739, 6272.372762723691, 6280.277768056732, 6292.716827742763, 6300.495960323255, 6035.78874739382, 6176.598521096114, 6187.81436556904, 6213.2233470816145, 6228.750849966132, 6187.938120618916, 6240.93904036324, 6267.5299352549655, 6271.717772700148, 6276.529260305404, 6109.816705498843, 6112.388761123852, 6120.329878791587, 6124.6325220486915, 6129.233845769279, 6108.328083910677, 6144.787876364595, 6164.951958167212, 6165.599236049642, 6172.321044685171, 5738.598298119258, 5799.971000144972, 5806.738696758572, 5859.736311865891, 5871.383504731942, 5851.707414629166, 5890.293164820249, 5900.232969713909, 5912.763489460373, 5979.790707325223, 4705.64707646921, 5745.339285981999, 5810.767569297181, 5828.5919985602295, 5837.445442682938, 5906.911396328973, 5959.702014899324, 5985.221921150222, 5988.610740301867, 6002.789902353396, 5630.4369563043365, 5702.0, 5702.857428564319, 5738.713064346683, 5794.739236734273], "value": 5585.2124350776, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.7111746304607, 514.5317760837638, 517.9663321884075, 519.974001299935, 537.9139337705968, 38365.80817095914, 39598.802005989965, 39907.99999999996, 42148.0, 42149.57850421495, 40003.59996400035, 40477.19045619093, 40489.797551012205, 40569.0, 40609.39085913714, 9296.72109836706, 9399.577019034126, 9447.338686291961, 9462.52687365629, 9481.241500679964, 9440.339176257665, 9536.046395360478, 9547.761305967371, 9560.760980975461, 9566.186874115709, 9498.430094194337, 9542.713718588453, 9547.952260238724, 9564.665236716717, 9568.56941437637, 9465.148136667733, 9479.146876781087, 9543.90245121813, 9549.140577348073, 9560.95219523905, 7788.610569471503, 9397.859032114524, 9502.714918552454, 9504.572294246705, 9505.0, 39435.0, 39527.8023609883, 39589.80205098985, 39658.80170599157, 39686.0, 3302.78531895426, 3323.5180898769786, 3403.1151900505797, 3481.1645205150835, 3771.1891943232336, 22618.98214580348, 24264.878675606684, 29507.409851802844, 29826.254343641584, 31179.0, 2811.845348505831, 2928.857745479265, 3057.09815604395, 3097.9471614610507, 3242.3353212591364, 428543.0, 442363.0, 479635.60182199214, 479759.0, 480944.0, 45364.77317613457, 58727.70636146877, 58756.7062164695, 58823.0, 58862.11706824401, 49731.502684972686, 62163.0, 62345.75308493805, 62552.68723656353, 62633.0, 16014.959027663164, 16180.352785888432, 16191.83808161927, 16266.837331626532, 16317.428889988794, 12331.383430828422, 12375.319357435248, 12385.566505172275, 12386.071044671686, 12418.441170147365, 14756.926215368854, 18578.35057688108, 18761.249550017845, 18777.34279300218, 19092.045397730057, 7552.811179720441, 7575.431842611826, 7696.345810606162, 7769.53382797034, 7790.298873101448, 845.7251393297203, 985.383970288727, 1001.1690297053428, 1007.4056306778889, 1054.947252637365, 29834.95577654772, 29976.800927963075, 30158.246043849078, 30205.848970755444, 30630.846845766075, 905.0361365146098, 1067.5409573883148, 1068.2308737708838, 1083.9241253112207, 1128.904043156341, 13019.349032548334, 15058.472953446577, 15453.459128930434, 15536.533903982898, 15805.446809361618, 553241.0, 559638.0, 560224.0, 561288.0, 562829.0, 56254.43745562574, 56977.14534281989, 57023.85952281014, 57047.0, 57093.714531427075, 1187.5190547828142, 1336.7593833109938, 1355.6949686320697, 1375.8830499407463, 1383.280694039094, 28803.711962880105, 29353.0, 29373.853130735064, 29530.852345738993, 30127.24681882883, 49981.0, 50189.0, 50396.992060157856, 50592.74703626458, 50620.0, 549823.0, 550691.0, 551250.0, 551585.0, 552763.0, 26045.6093158599, 26432.207033789018, 26542.734572654026, 26856.194314170607, 26877.328066798487, 45594.0, 45851.0, 46607.766961164976, 46630.30054549252, 46813.76593117013, 33509.49735753917, 33620.99137025893, 33743.83128084344, 33914.66085339214, 34338.0, 37455.81272093622, 37581.4362784553, 38217.235655287295, 38279.234415312094, 38766.6123338763, 25135.0, 31511.212219693774, 32125.678743213204, 32257.0, 35542.822285888404, 31436.528452073693, 34315.0, 34328.82835585806, 35174.296514069065, 36039.099022523595, 20233.089510972335, 25010.874945625157, 25221.369465763502, 25285.87357063203, 25563.36091597725], "value": 19340.902372747172, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.9355031650302, 346.623913054336, 373.09524403321956, 380.6402949212994, 386.81626227541904, 15241.771373429407, 19219.32732354368, 19682.507937301576, 19919.701204481928, 20164.19343226269, 17938.578291930586, 19773.703394449094, 19809.702854457195, 20038.699419508717, 20122.49693757657, 5341.706206158659, 5942.70286485674, 5973.313068997056, 5985.221921150244, 5990.940090599103, 5610.663360198401, 5824.155497452867, 5893.499052580536, 5897.764089436439, 5904.0, 5349.866253343678, 5495.862603434906, 5599.468050535193, 5841.766329346821, 5856.970715146418, 5720.599558030939, 5814.796482123148, 5850.268716410449, 5859.2382990211, 5882.14708867214, 4878.609711223094, 5065.214891691794, 5155.974220128913, 5166.250893620404, 5174.456682048369, 24244.636330455058, 24331.391715207083, 24491.26526204217, 24540.63189052148, 24720.25839224808, 2230.542738738555, 2245.820334373246, 2276.64711969645, 2301.171578231835, 2377.3699969508093, 5660.033942494511, 6773.4242589379965, 6897.9655101724675, 14432.494862679756, 14748.115113093249, 1838.6138910828608, 1841.300305883769, 1854.8887066776037, 1879.539512819368, 1883.8775479593783, 118134.99999999914, 118411.0, 120006.0, 121408.0, 121863.78136218613, 9226.907730922605, 10730.73173170677, 10743.78512429747, 11119.55521779136, 11236.044936180353, 11486.94256528712, 13552.322383880766, 13602.0, 13626.0, 13637.113587616774, 8223.54770487617, 8434.57827108642, 8438.987321521457, 8545.871811922827, 8581.828363432822, 8374.790630234293, 8431.241188293083, 8483.278921291636, 8572.742817715478, 8590.269827064645, 4754.453237877619, 6637.46900247979, 6651.534392592569, 6677.098591690156, 6677.76627818024, 1587.928543215535, 1867.094459187293, 1873.9437816865516, 1878.1548303263587, 1893.6686079936233, 937.7327461673391, 948.6252930092575, 957.5164541906432, 986.8470387090013, 1008.3193844155165, 18585.90707046456, 19929.402117936486, 20882.477938051365, 21747.130114795233, 21827.12691492354, 930.6417029443713, 932.7761337279004, 944.7449188719007, 995.8000609265818, 997.1574019953129, 11374.829377559343, 11665.650030499099, 11675.474603642857, 11681.707957300965, 11700.0, 545310.2734486302, 585621.0, 586585.0, 598990.0, 599377.0, 78806.42387152225, 111301.0, 113649.72700545943, 119378.0, 119776.0, 907.2968449451596, 1000.5747557288123, 1015.1422048369117, 1015.5379302417382, 1019.2050200843354, 14636.926815366282, 17916.641667166325, 18649.813501864806, 19163.520911977314, 19361.32235371755, 36042.85692938395, 46546.069078618915, 48099.75950120227, 48162.0, 48463.75768121137, 66877.32806679871, 66949.0, 66951.0, 67153.32846671666, 67946.0, 6462.773802916875, 7801.648925798239, 7845.450818442654, 8101.270885620323, 8115.066767321833, 7034.0, 9381.577828997848, 12639.304838233986, 12706.618801435972, 12814.231146131262, 10097.5456104474, 10492.169242038353, 10744.194185435967, 10782.784344313226, 11425.942870285595, 10396.948015259875, 10820.89179108199, 10916.945415272872, 11060.944695276472, 11242.213045086766, 10041.49792510357, 10870.293430926964, 11366.431678416211, 11566.826497602711, 11622.418879056182, 7777.261160189773, 7871.0554733432355, 8063.475874068166, 8215.178482151738, 8249.29880960125, 10896.237263391486, 11005.064569511682, 11269.154813388865, 11274.60538881168, 11318.037966772916], "value": 9319.680230276143, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1278.750643624493, 1458.5624312706188, 1597.7603359496081, 1762.1893928792754, 1792.4891405949306, 7501.0, 7775.416843736714, 7939.761807145787, 8445.32437405007, 8454.91545084549, 7964.641591128398, 8210.58947052648, 8239.876401853977, 8348.206920342558, 8352.958235208815, 2296.3455415206713, 2319.7332306784683, 2320.9767902320928, 2324.8140148788143, 2329.6720869104615, 2312.2138472919205, 2348.58899692554, 2359.3629719975597, 2363.385519764865, 2370.8340416170877, 2286.325533967484, 2291.46150654596, 2310.90756369745, 2317.1194945920524, 2320.0487800002, 2270.0919632147106, 2279.2706333973133, 2302.1366987379733, 2308.7575804540534, 2314.143766806284, 2289.645105008726, 2304.469971906471, 2308.8499247549025, 2316.0388438797877, 2320.4662927526765, 8217.260446559778, 8389.286910612605, 8412.453190542596, 8412.915870841274, 8442.94463192101, 5078.542931136179, 5205.8958820823755, 5226.973865130688, 5240.502152295526, 5244.239585260116, 3267.803931764102, 3429.657034296575, 3520.77114987525, 3587.210813620999, 3601.837917293728, 1015.2892974917587, 1028.3726926574784, 1082.4046774274118, 1083.4636854756866, 1091.5688303120303, 10743.623973160902, 10790.838137427867, 10856.131509479144, 10868.239223254375, 10870.293430926964, 4176.867349487884, 4401.801918913657, 4487.349334346502, 4697.13103075932, 4739.95260047402, 9335.673251436167, 9393.812123757487, 9426.151646351862, 9437.103475169848, 9452.10205030521, 5200.141976573823, 5204.193350030751, 5211.1662134059125, 5215.400228973641, 5224.791008359699, 4299.0, 4325.300063328688, 4711.882202944954, 4744.122337367598, 4782.067496838084, 3818.522684664417, 3846.653801157909, 3913.5108111486065, 3931.8427262909736, 3952.7430717003303, 1009.6264382178495, 1070.2615195515225, 1126.5155982927256, 1249.3275150935617, 1280.1870812034426, 802.8314054048715, 887.3123329419623, 905.610587447404, 911.366600212859, 919.0166521821586, 4693.460252070987, 5764.538836893039, 5801.274840644919, 5817.650940943557, 5855.882882342329, 853.2448782827242, 937.3391758810022, 960.7357976556364, 1014.1683819268277, 1031.6853359725205, 3725.2549490102087, 3759.7180211484247, 3836.9808150959066, 3858.0, 3881.6312450317173, 9828.852567211497, 10175.033371829664, 10195.235357348229, 10202.489875506195, 10216.591336346612, 10150.746231344276, 10163.7459063524, 10180.541875615616, 10180.898191017995, 10188.032136946978, 7667.424943129176, 7793.688252469951, 7925.167857374892, 7943.324817390587, 7951.801204969922, 5458.208559758892, 5489.451054894478, 5559.777608895599, 5576.079946808731, 5627.352854421794, 9240.630374784932, 9362.016988216139, 9392.436453812794, 9416.246700264099, 9436.339456237998, 9226.169644732006, 9405.670801521914, 9465.716028519155, 9514.857277140987, 9578.952105239428, 6681.76613818514, 6754.6622668865375, 6795.28649491816, 6814.4548436124, 6857.382835544825, 7821.1396746359, 7894.447388682737, 7904.051513818378, 7906.16985216544, 7932.6430310635005, 6995.650217489207, 7051.788446346618, 7120.572765634079, 7144.928550714426, 7259.12890453149, 6891.896621550581, 7026.367626913602, 7028.859422811617, 7117.217106118426, 7143.749968751069, 6712.295209002984, 6712.2952090031795, 6766.424853887278, 6784.964510646688, 6787.796366109024, 4799.616030717464, 4819.373481447389, 4825.879353016203, 4832.855014349575, 4869.342638743724, 4787.640926930564, 4834.0, 4938.012397520439, 4946.579540738933, 4948.727819969792], "value": 4416.505608962617, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.7203300930134, 303.55680706168994, 310.01879052797904, 311.8783674366999, 314.90238026211875, 5181.637285390024, 5211.088059589571, 5276.103062479378, 5498.505134537891, 5511.641743286688, 4731.793392684861, 5157.355330583677, 5360.775278259527, 5401.810936617219, 5466.371367292762, 3559.8608445297514, 4051.817668204923, 4090.4456306603447, 4121.372058037074, 4147.8963025924295, 3818.885433437001, 4080.3879418087226, 4111.917761644765, 4146.9792651036705, 4162.562930892258, 3554.9111272218142, 3731.2724018816343, 4024.5774193709676, 4039.939400908989, 4061.5630621627083, 3436.226848958989, 3889.8444062237477, 4047.453593764863, 4072.775997320161, 4075.3071977763702, 3261.0, 3522.330757156132, 3551.1122219445137, 3554.697850682695, 3568.3041806847414, 4897.828575999859, 5180.6373553851245, 5222.0, 5251.560228050633, 5295.0998330283555, 3792.6965842732516, 4558.566936141061, 4567.447067996897, 4603.861884143481, 4643.512431194709, 5622.550195984352, 5623.718814059281, 5639.774409023634, 5661.858453538694, 5741.799037033684, 3685.539307586552, 3832.9808350958347, 3850.191459793447, 3850.903727406809, 3853.325668008087, 5633.971830140823, 5860.355360910296, 5887.322957859858, 5891.823245302648, 5962.869493515939, 3442.8106454144763, 3631.291898079849, 3657.195417008254, 3914.549826769901, 3941.428492868575, 3098.5352197170605, 3338.015285490786, 3778.2254637799183, 3981.0, 3994.1227623017135, 3569.8929032129076, 4173.64524015456, 4581.65637577183, 4611.754826196908, 4617.492075871652, 5690.374058853523, 5720.141978703145, 5737.3975732548315, 5749.655020698771, 5755.740991655385, 1045.5660900726114, 1196.7367179220557, 1231.4766224354792, 1247.7878760610718, 1261.0164072023836, 756.8637645223913, 845.8012367093728, 870.1429092344036, 872.9655358400316, 875.2297977779514, 4519.796609152596, 4840.661153719206, 4956.654258375582, 5004.699718016858, 5036.0, 4897.559219670246, 5486.202036961099, 5486.61593688438, 5552.41699621542, 5586.804461843816, 4008.6392224699916, 4793.808247670124, 4824.372831531949, 4988.55103040728, 5045.5459008689395, 2661.9068332608267, 2863.985680071586, 2876.9712302876706, 2920.3575213453432, 2926.5610158475974, 5684.715764211773, 5685.3177618685195, 5697.772089116389, 5739.887020323342, 5796.275465566804, 5150.24032456309, 5193.766280517386, 5573.442655734475, 5685.536853569928, 5748.770049198069, 5615.15361977047, 5621.550275977912, 5634.746436410372, 5653.575981801299, 5659.603827732101, 4500.774961251859, 5472.425395333592, 5508.779648814163, 5565.554755619622, 5573.0, 4826.356101143614, 5131.409263128442, 5396.571542805675, 5404.2163886235485, 5510.366307874646, 5187.6682969719195, 5302.681839089667, 5591.6365436246515, 5627.803026894039, 5640.492355687844, 3120.60992375953, 3415.565462505704, 3516.8394429838604, 3704.4258139988347, 3713.20166164276, 4729.408823897013, 5446.183072539151, 5612.979327442036, 5804.767809287581, 5961.7615295387695, 5618.297712785902, 5684.459976302244, 5704.401037890962, 5706.229658996149, 5730.283714535683, 5748.166515855094, 5789.56578256624, 5791.683326669366, 5792.94207057924, 5814.389489103583, 4646.652470783435, 5231.885634064495, 5464.453554644663, 5604.607677462702, 5704.315482142004, 5445.782168713207, 5645.70148865755, 5706.9714651426475, 5738.626989245686, 5806.796762113306, 4354.477462704495, 4521.1409832131785, 5563.193336966036, 5577.3028371453565, 5615.326160860723], "value": 4040.7171068700254, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.4796390941383, 710.875596770565, 717.6053170756082, 764.1479750078664, 779.3299542588701, 11533.826992595097, 13972.231527265996, 14684.559463216106, 14690.559283221493, 14782.482613108543, 11814.113941454383, 13196.802047969288, 13350.66623334417, 14097.506587269421, 14875.107493550395, 811.5049819610033, 842.751388340441, 845.2012847858776, 845.564534264851, 846.1158089796147, 823.086374124721, 831.9500829950192, 832.9000519937586, 835.9916400836005, 837.5979529825687, 882.3338379523451, 885.3315746611331, 887.4852585500395, 889.5374405309235, 889.7953470701742, 863.8272345530919, 879.6393478673713, 880.2078129683305, 883.2227639677108, 884.6903583745702, 650.5576208178437, 656.2551504042946, 656.2649832187932, 656.7734131724548, 658.1246941567704, 13833.170009799343, 13899.583012509642, 13901.652458688613, 13928.094673846268, 13942.721145577032, 12985.35073246334, 13295.667608309774, 13412.195268283935, 13483.325833708275, 13500.797488037688, 11004.229703920728, 11183.769785323608, 11338.943305283421, 11385.761828248533, 11426.37154956477, 3556.590992035923, 3637.290728307981, 3683.723720720957, 3733.9253214935547, 3798.9430158547643, 83746.99999999939, 83992.58003709943, 86031.56984215102, 86591.0, 87194.0, 10531.94734026325, 10590.152787776959, 10668.786624267626, 10694.893051069545, 10719.249652524404, 17132.314707411562, 20985.95070246512, 21010.579788404146, 21097.78902210989, 21339.25312614051, 7581.50720203185, 7610.7822748359995, 7658.310752032344, 7660.080790305199, 7837.981062461843, 3817.1602247505502, 4090.141070375195, 4237.660987121023, 4252.638525725348, 4258.914821703549, 3366.3940490711416, 3437.2781715839956, 3486.163320802989, 3494.0, 3497.89506314811, 484.36064394998965, 526.2211926349025, 526.276369991262, 540.2112915143855, 559.680981840347, 603.3182503770711, 612.9417705317987, 615.2033117113349, 644.4135836388864, 665.8867992441296, 7766.961165194138, 8825.558722063999, 9277.025912279249, 9793.167580755715, 9826.4104153751, 558.6145559563888, 562.7748900439857, 589.6727316339387, 667.5093806052645, 668.2916108924555, 4214.3889136075095, 4241.745495270294, 4443.7111587746695, 4465.196264672325, 4470.932936005963, 107301.4634926836, 110125.0, 115502.4224878887, 116207.0, 120807.39596301962, 75832.1041973955, 88670.0, 89055.0, 94998.0, 104312.0, 54042.37872863821, 58883.0, 59095.0, 81795.0, 83640.16359836445, 9328.207102396373, 9598.08937564952, 9627.037296270317, 9673.123666480067, 9726.70819875405, 39075.48320651155, 41125.588744112174, 46848.765756171, 49140.75429622829, 50262.246066308304, 33248.50127248045, 50246.0, 51103.46689599318, 58873.70563147157, 66926.8868042403, 11963.222390544586, 12160.148789584644, 12197.75604487923, 12543.0, 12754.0, 12915.806262906252, 13075.215487070805, 14046.227457489937, 14160.504382346568, 14363.56909292723, 12658.87341126577, 12841.614751557468, 13038.348082596021, 13148.079634425498, 13165.802512962506, 12550.1842380245, 12939.288339141438, 13024.153430027018, 13153.145045572008, 13465.259410732504, 12630.421197350332, 12922.095453318178, 12927.935360323138, 13020.28388438607, 13048.673783155496, 9682.559504483073, 11031.448427578429, 11081.224314297922, 11119.943605357477, 11610.303381797119, 10749.57199215958, 11286.887131128911, 11476.770464590829, 11480.598179063692, 11541.076713862703], "value": 7097.529961535359, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1503.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 117.0, 118.0, 125.0, 118.0, 116.0, 116.0, 116.0, 98.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 115.0, 113.0, 146.0, 113.0, 112.0, 115.0, 114.0, 139.0, 97.0], "value": 121.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 200.0, 197.0, 202.0, 198.0, 196.0, 196.0, 195.0, 194.0, 194.0], "value": 196.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 61.0, 85.0, 62.0, 59.0, 59.0, 58.0, 82.0, 59.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 72.0, 90.0, 72.0, 69.0, 102.0, 72.0, 92.0, 100.0, 124.0], "value": 87.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 123.0, 89.0, 88.0, 135.0, 86.0, 99.0, 87.0, 89.0, 87.0], "value": 101.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 121.0, 151.0, 95.0], "value": 103.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 129.0, 88.0, 97.0, 88.0, 89.0, 91.0, 98.0, 130.0, 88.0], "value": 103.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 56.0, 60.0, 60.0, 62.0, 60.0, 60.0, 61.0, 60.0], "value": 59.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 71.0, 76.0, 72.0, 69.0, 69.0, 72.0, 71.0, 72.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 88.0, 83.0, 83.0, 87.0, 87.0, 85.0, 89.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 140.0, 138.0, 171.0, 130.0, 145.0, 145.0, 154.0, 214.0, 158.0], "value": 153.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 168.0, 169.0, 171.0, 168.0, 168.0, 169.0, 171.0, 166.0], "value": 168.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 67.0, 68.0, 67.0, 68.0, 67.0, 68.0, 71.0, 67.0], "value": 67.8, "unit": "score"}]}]} 12:44:15 INFO - Return code: 0 12:44:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:44:16 ERROR - # TBPL SUCCESS # 12:44:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:44:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:44:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:44:16 INFO - 2017-01-09 12:44:16.042 screenresolution[1929:14498] starting screenresolution argv=screenresolution get 12:44:16 INFO - 2017-01-09 12:44:16.045 screenresolution[1929:14498] Display 0: 1600x1200x32@60 12:44:16 INFO - 2017-01-09 12:44:16.052 screenresolution[1930:14501] starting screenresolution argv=screenresolution list 12:44:16 INFO - Available Modes on Display 0 12:44:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:44:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:44:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:44:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:44:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:44:16 INFO - Intel Iris: 12:44:16 INFO - Chipset Model: Intel Iris 12:44:16 INFO - Type: GPU 12:44:16 INFO - Bus: Built-In 12:44:16 INFO - VRAM (Dynamic, Max): 1536 MB 12:44:16 INFO - Vendor: Intel (0x8086) 12:44:16 INFO - Device ID: 0x0a2e 12:44:16 INFO - Revision ID: 0x0009 12:44:16 INFO - Displays: 12:44:16 INFO - Display: 12:44:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:44:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:44:16 INFO - Display Serial Number: 1600x1200 60 12:44:16 INFO - Main Display: Yes 12:44:16 INFO - Mirror: Off 12:44:16 INFO - Online: Yes 12:44:16 INFO - Rotation: Supported 12:44:16 INFO - Return code: 0 12:44:16 INFO - Running post-action listener: _resource_record_post_action 12:44:16 INFO - [mozharness: 2017-01-09 20:44:16.298468Z] Finished run-tests step (success) 12:44:16 INFO - Running post-run listener: _resource_record_post_run 12:44:16 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 913408; Write bytes: 661704192; Read time: 13; Write time: 1948 12:44:16 INFO - TinderboxPrint: CPU usage
54.5% 12:44:16 INFO - TinderboxPrint: I/O read bytes / time
913,408 / 13 12:44:16 INFO - TinderboxPrint: I/O write bytes / time
661,704,192 / 1,948 12:44:16 INFO - TinderboxPrint: CPU idle
2,201.1 (45.5%) 12:44:16 INFO - TinderboxPrint: CPU system
978.8 (20.2%) 12:44:16 INFO - TinderboxPrint: CPU user
1,657.9 (34.3%) 12:44:16 INFO - TinderboxPrint: Swap in / out
350,302,208 / 0 12:44:16 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 343996928; Write bytes: 343078400; Read time: 26782; Write time: 732 12:44:16 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 913408; Write bytes: 308451328; Read time: 13; Write time: 1177 12:44:16 INFO - Running post-run listener: _upload_blobber_files 12:44:16 INFO - Blob upload gear active. 12:44:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:44:16 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 12:44:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:44:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:44:17 INFO - (blobuploader) - INFO - Open directory for files ... 12:44:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:44:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:44:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:44:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:44:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:44:17 INFO - (blobuploader) - INFO - Done attempting. 12:44:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:44:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:44:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:44:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:44:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:44:19 INFO - (blobuploader) - INFO - Done attempting. 12:44:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:44:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:44:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:44:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:44:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:44:21 INFO - (blobuploader) - INFO - Done attempting. 12:44:21 INFO - (blobuploader) - INFO - Iteration through files over. 12:44:21 INFO - Return code: 0 12:44:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:44:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:44:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1e0bdb0229f7bbd7d15c6f323af1ea5d57ab0467c96af40d5a08b7bcd5b5cfed1071fca04801cdb3e6f9427504d6a823a75052fbe460975f88f2449c07292cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2bd50a8a5eb1df1c6d0234d29daa9185375f88dac7797888b57cacdbbece2469bdb8c3aaacf1cf3b5f98d3f8e08ca76faa6199f995e9935947ad4a3655665f01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d98ebee819e4cb44028e0f7fd7f787c14942cb9fb277d772ab68dc6db829243af008a204e30b2183e19373da4826238f1000de1e1bc70c54cf68777cd7e0d645"} 12:44:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:44:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:44:21 INFO - Contents: 12:44:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1e0bdb0229f7bbd7d15c6f323af1ea5d57ab0467c96af40d5a08b7bcd5b5cfed1071fca04801cdb3e6f9427504d6a823a75052fbe460975f88f2449c07292cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2bd50a8a5eb1df1c6d0234d29daa9185375f88dac7797888b57cacdbbece2469bdb8c3aaacf1cf3b5f98d3f8e08ca76faa6199f995e9935947ad4a3655665f01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d98ebee819e4cb44028e0f7fd7f787c14942cb9fb277d772ab68dc6db829243af008a204e30b2183e19373da4826238f1000de1e1bc70c54cf68777cd7e0d645"} 12:44:21 INFO - Running post-run listener: copy_logs_to_upload_dir 12:44:21 INFO - Copying logs to upload dir... 12:44:21 INFO - mkdir: /builds/slave/test/build/upload/logs 12:44:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.976203 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-09 12:44:21.448994) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 12:44:21.452457) ========= 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.OIlsDNZ1YR/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.9NUlnMou5F/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/Mozilla-Aurora/sha512/f1e0bdb0229f7bbd7d15c6f323af1ea5d57ab0467c96af40d5a08b7bcd5b5cfed1071fca04801cdb3e6f9427504d6a823a75052fbe460975f88f2449c07292cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2bd50a8a5eb1df1c6d0234d29daa9185375f88dac7797888b57cacdbbece2469bdb8c3aaacf1cf3b5f98d3f8e08ca76faa6199f995e9935947ad4a3655665f01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d98ebee819e4cb44028e0f7fd7f787c14942cb9fb277d772ab68dc6db829243af008a204e30b2183e19373da4826238f1000de1e1bc70c54cf68777cd7e0d645"} build_url:https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011246 build_url: 'https://queue.taskcluster.net/v1/task/El1I-y8RQSiMbEUT7vUwlw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1e0bdb0229f7bbd7d15c6f323af1ea5d57ab0467c96af40d5a08b7bcd5b5cfed1071fca04801cdb3e6f9427504d6a823a75052fbe460975f88f2449c07292cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2bd50a8a5eb1df1c6d0234d29daa9185375f88dac7797888b57cacdbbece2469bdb8c3aaacf1cf3b5f98d3f8e08ca76faa6199f995e9935947ad4a3655665f01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d98ebee819e4cb44028e0f7fd7f787c14942cb9fb277d772ab68dc6db829243af008a204e30b2183e19373da4826238f1000de1e1bc70c54cf68777cd7e0d645"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 12:44:21.487352) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 12:44:21.487687) ========= 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.OIlsDNZ1YR/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.9NUlnMou5F/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.004859 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 12:44:21.536884) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 12:44:21.537270) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 12:44:21.537604) ========= ========= Total master_lag: 0.20 =========