builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0318 starttime: 1492284172.64 results: success (0) buildid: 20170415110806 builduid: 0600e9faea054afc806ac02021c688f4 revision: ce69b6e1773e9e0d0a190ce899f34b1658e66ca4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-15 12:22:52.636763) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-15 12:22:52.638462) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-15 12:22:52.638941) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-15 12:22:52.755239) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 12:22:52.755641) ========= 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.QzfUAG9JSe/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.b3OtLjOeo1/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-15 12:22:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.4M=0.001s 2017-04-15 12:22:53 (12.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.545187 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 12:22:53.355607) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 12:22:53.355968) ========= 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.QzfUAG9JSe/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.b3OtLjOeo1/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.040917 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 12:22:53.456667) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-15 12:22:53.457128) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ce69b6e1773e9e0d0a190ce899f34b1658e66ca4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ce69b6e1773e9e0d0a190ce899f34b1658e66ca4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QzfUAG9JSe/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.b3OtLjOeo1/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-15 12:22:53,564 truncating revision to first 12 chars 2017-04-15 12:22:53,564 Setting DEBUG logging. 2017-04-15 12:22:53,564 attempt 1/10 2017-04-15 12:22:53,564 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ce69b6e1773e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-15 12:22:54,677 unpacking tar archive at: mozilla-central-ce69b6e1773e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.396600 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-15 12:22:54.879241) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-15 12:22:54.879772) ========= script_repo_revision: ce69b6e1773e9e0d0a190ce899f34b1658e66ca4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-15 12:22:54.880449) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-15 12:22:54.880833) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-15 12:22:54.901675) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-04-15 12:22:54.902131) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QzfUAG9JSe/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.b3OtLjOeo1/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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:22:55 INFO - MultiFileLogger online at 20170415 12:22:55 in /builds/slave/test 12:22:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:22:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:22:55 INFO - {'append_to_log': False, 12:22:55 INFO - 'base_work_dir': '/builds/slave/test', 12:22:55 INFO - 'blob_upload_branch': 'Firefox', 12:22:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:22:55 INFO - 'branch': 'Firefox', 12:22:55 INFO - 'buildbot_json_path': 'buildprops.json', 12:22:55 INFO - 'config_files': ('talos/mac_config.py',), 12:22:55 INFO - 'default_actions': ('clobber', 12:22:55 INFO - 'read-buildbot-config', 12:22:55 INFO - 'download-and-extract', 12:22:55 INFO - 'populate-webroot', 12:22:55 INFO - 'create-virtualenv', 12:22:55 INFO - 'install', 12:22:55 INFO - 'run-tests'), 12:22:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:22:55 INFO - 'download_minidump_stackwalk': True, 12:22:55 INFO - 'download_symbols': 'ondemand', 12:22:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:22:55 INFO - 'tooltool.py': '/tools/tooltool.py', 12:22:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:22:55 INFO - '/tools/misc-python/virtualenv.py')}, 12:22:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:22:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:22:55 INFO - 'gecko_profile': False, 12:22:55 INFO - 'gecko_profile_interval': 0, 12:22:55 INFO - 'installer_path': 'installer.exe', 12:22:55 INFO - 'log_level': 'info', 12:22:55 INFO - 'log_name': 'talos', 12:22:55 INFO - 'log_to_console': True, 12:22:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:22:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:22:55 INFO - 'opt_config_files': (), 12:22:55 INFO - 'pip_index': False, 12:22:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:22:55 INFO - 'cmd': ('bash', 12:22:55 INFO - '-c', 12:22:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:22:55 INFO - 'enabled': True, 12:22:55 INFO - 'halt_on_failure': False, 12:22:55 INFO - 'name': 'check_screen_resolution'},), 12:22:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:22:55 INFO - 'cmd': ('bash', 12:22:55 INFO - '-c', 12:22:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:22:55 INFO - 'enabled': True, 12:22:55 INFO - 'halt_on_failure': False, 12:22:55 INFO - 'name': 'check_screen_resolution'},), 12:22:55 INFO - 'run_cmd_checks_enabled': True, 12:22:55 INFO - 'suite': 'dromaeojs-e10s', 12:22:55 INFO - 'system_bits': '32', 12:22:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:22:55 INFO - 'title': 't-yosemite-r7-0318', 12:22:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:22:55 INFO - 'use_talos_json': True, 12:22:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:22:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:22:55 INFO - 'work_dir': 'build'} 12:22:55 INFO - [mozharness: 2017-04-15 19:22:55.112909Z] Running clobber step. 12:22:55 INFO - Running pre-action listener: _resource_record_pre_action 12:22:55 INFO - Running main action method: clobber 12:22:55 INFO - rmtree: /builds/slave/test/build 12:22:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:22:57 INFO - Running post-action listener: _resource_record_post_action 12:22:57 INFO - [mozharness: 2017-04-15 19:22:57.686299Z] Finished clobber step (success) 12:22:57 INFO - [mozharness: 2017-04-15 19:22:57.686551Z] Running read-buildbot-config step. 12:22:57 INFO - Running pre-action listener: _resource_record_pre_action 12:22:57 INFO - Running main action method: read_buildbot_config 12:22:57 INFO - Using buildbot properties: 12:22:57 INFO - { 12:22:57 INFO - "project": "", 12:22:57 INFO - "product": "firefox", 12:22:57 INFO - "script_repo_revision": "production", 12:22:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:22:57 INFO - "repository": "", 12:22:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:22:57 INFO - "buildid": "20170415110806", 12:22:57 INFO - "pgo_build": "False", 12:22:57 INFO - "basedir": "/builds/slave/test", 12:22:57 INFO - "buildnumber": 47, 12:22:57 INFO - "slavename": "t-yosemite-r7-0318", 12:22:57 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:22:57 INFO - "platform": "yosemite_r7", 12:22:57 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:22:57 INFO - "branch": "mozilla-central", 12:22:57 INFO - "repo_path": "mozilla-central", 12:22:57 INFO - "revision": "ce69b6e1773e9e0d0a190ce899f34b1658e66ca4", 12:22:57 INFO - "stage_platform": "macosx64", 12:22:57 INFO - "builduid": "0600e9faea054afc806ac02021c688f4", 12:22:57 INFO - "slavebuilddir": "test" 12:22:57 INFO - } 12:22:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 12:22:57 INFO - Running post-action listener: _resource_record_post_action 12:22:57 INFO - [mozharness: 2017-04-15 19:22:57.692329Z] Finished read-buildbot-config step (success) 12:22:57 INFO - [mozharness: 2017-04-15 19:22:57.692538Z] Running download-and-extract step. 12:22:57 INFO - Running pre-action listener: _resource_record_pre_action 12:22:57 INFO - Running main action method: download_and_extract 12:22:57 INFO - mkdir: /builds/slave/test/build/tests 12:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:57 INFO - trying https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 12:22:57 INFO - Downloading https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:22:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 12:22:58 INFO - Downloaded 1325 bytes. 12:22:58 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:22:58 INFO - Using the following test package requirements: 12:22:58 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:22:58 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 12:22:58 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:22:58 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:22:58 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 12:22:58 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:22:58 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 12:22:58 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:22:58 INFO - u'jsshell-mac.zip'], 12:22:58 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:22:58 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 12:22:58 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:22:58 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:22:58 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 12:22:58 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:22:58 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 12:22:58 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:22:58 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 12:22:58 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:22:58 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 12:22:58 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:22:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:22:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:22:58 INFO - Fetch https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:23:00 INFO - Expected file size: 52664071 12:23:00 INFO - Obtained file size: 52664071 12:23:16 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:23:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:23:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:23:16 INFO - Fetch https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:23:18 INFO - Expected file size: 52664071 12:23:18 INFO - Obtained file size: 52664071 12:23:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 12:23:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:23:33 INFO - Fetch https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 12:23:34 INFO - Expected file size: 14408802 12:23:34 INFO - Obtained file size: 14408802 12:23:34 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:34 INFO - trying https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:23:34 INFO - Downloading https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 12:23:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 12:23:36 INFO - Downloaded 71440744 bytes. 12:23:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:23:36 INFO - mkdir: /builds/slave/test/properties 12:23:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:23:36 INFO - Writing to file /builds/slave/test/properties/build_url 12:23:36 INFO - Contents: 12:23:36 INFO - build_url:https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:23:37 INFO - Running post-action listener: _resource_record_post_action 12:23:37 INFO - Running post-action listener: set_extra_try_arguments 12:23:37 INFO - [mozharness: 2017-04-15 19:23:37.513325Z] Finished download-and-extract step (success) 12:23:37 INFO - [mozharness: 2017-04-15 19:23:37.513477Z] Running populate-webroot step. 12:23:37 INFO - Running pre-action listener: _resource_record_pre_action 12:23:37 INFO - Running main action method: populate_webroot 12:23:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:23:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:23:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:23:37 INFO - '--tppagecycles', 12:23:37 INFO - '7'], 12:23:37 INFO - 'tests': ['tsvgm']}}, 12:23:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:23:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:23:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:23:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:23:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:23:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:23:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:23:37 INFO - 'talos_options': ['--disable-e10s'], 12:23:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:23:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:23:37 INFO - 'talos_options': ['--disable-e10s'], 12:23:37 INFO - 'tests': ['damp', 'tps']}, 12:23:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:23:37 INFO - 'tests': ['damp', 'tps']}, 12:23:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:23:37 INFO - 'tests': ['dromaeo_dom']}, 12:23:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:23:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:23:37 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:23:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:23:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:23:37 INFO - 'tests': ['a11yr', 12:23:37 INFO - 'ts_paint', 12:23:37 INFO - 'tpaint', 12:23:37 INFO - 'sessionrestore', 12:23:37 INFO - 'sessionrestore_no_auto_restore', 12:23:37 INFO - 'tabpaint']}, 12:23:37 INFO - 'other-e10s': {'tests': ['a11yr', 12:23:37 INFO - 'ts_paint', 12:23:37 INFO - 'tpaint', 12:23:37 INFO - 'sessionrestore', 12:23:37 INFO - 'sessionrestore_no_auto_restore', 12:23:37 INFO - 'tabpaint']}, 12:23:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:23:37 INFO - 'ts_paint', 12:23:37 INFO - 'tpaint', 12:23:37 INFO - 'sessionrestore', 12:23:37 INFO - 'sessionrestore_no_auto_restore', 12:23:37 INFO - 'tabpaint']}, 12:23:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:23:37 INFO - 'ts_paint', 12:23:37 INFO - 'tpaint', 12:23:37 INFO - 'sessionrestore', 12:23:37 INFO - 'sessionrestore_no_auto_restore', 12:23:37 INFO - 'tabpaint']}, 12:23:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:23:37 INFO - 'tests': ['a11yr', 12:23:37 INFO - 'ts_paint', 12:23:37 INFO - 'tpaint', 12:23:37 INFO - 'sessionrestore', 12:23:37 INFO - 'sessionrestore_no_auto_restore', 12:23:37 INFO - 'tabpaint']}, 12:23:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:23:37 INFO - 'tests': ['a11yr', 12:23:37 INFO - 'ts_paint', 12:23:37 INFO - 'tpaint', 12:23:37 INFO - 'sessionrestore', 12:23:37 INFO - 'sessionrestore_no_auto_restore', 12:23:37 INFO - 'tabpaint']}, 12:23:37 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:23:37 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:23:37 INFO - 'bloom_basic_ref']}, 12:23:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:23:37 INFO - 'tests': ['tsvgx', 12:23:37 INFO - 'tsvgr_opacity', 12:23:37 INFO - 'tart', 12:23:37 INFO - 'tscrollx', 12:23:37 INFO - 'cart', 12:23:37 INFO - 'tsvg_static']}, 12:23:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:23:37 INFO - 'tsvgr_opacity', 12:23:37 INFO - 'tart', 12:23:37 INFO - 'tscrollx', 12:23:37 INFO - 'cart', 12:23:37 INFO - 'tsvg_static']}, 12:23:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:23:37 INFO - 'talos_options': ['--disable-e10s'], 12:23:37 INFO - 'tests': ['tp5o']}, 12:23:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:23:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:23:37 INFO - 'talos_options': ['--disable-e10s', 12:23:37 INFO - '--xperf_path', 12:23:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:37 INFO - 'tests': ['tp5n']}, 12:23:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:23:37 INFO - 'talos_options': ['--xperf_path', 12:23:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:37 INFO - 'tests': ['tp5n']}}, 12:23:37 INFO - 'talos.zip': {'path': '', 12:23:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:23:37 INFO - Running post-action listener: _resource_record_post_action 12:23:37 INFO - [mozharness: 2017-04-15 19:23:37.532929Z] Finished populate-webroot step (success) 12:23:37 INFO - [mozharness: 2017-04-15 19:23:37.533066Z] Running create-virtualenv step. 12:23:37 INFO - Running pre-action listener: _resource_record_pre_action 12:23:37 INFO - Running main action method: create_virtualenv 12:23:37 INFO - Creating virtualenv /builds/slave/test/build/venv 12:23:37 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:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:23:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:23:37 INFO - Using real prefix '/tools/python27' 12:23:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:23:38 INFO - Installing distribute.............................................................................................................................................................................................done. 12:23:41 INFO - Installing pip.................done. 12:23:41 INFO - Return code: 0 12:23:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:23:41 INFO - Reading from file tmpfile_stdout 12:23:41 INFO - Output received: 12:23:41 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:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:41 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 0x10fb8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd85982d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f969d30>, '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 0x10f88fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f95ebe8>, '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.QzfUAG9JSe/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.b3OtLjOeo1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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:41 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:41 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:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QzfUAG9JSe/Render', 12:23:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:41 INFO - 'HOME': '/Users/cltbld', 12:23:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:41 INFO - 'LOGNAME': 'cltbld', 12:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:41 INFO - 'MOZ_NO_REMOTE': '1', 12:23:41 INFO - 'NO_EM_RESTART': '1', 12:23:41 INFO - 'PAGER': '/bin/cat', 12:23:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:41 INFO - 'PWD': '/builds/slave/test', 12:23:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:41 INFO - 'SHELL': '/bin/bash', 12:23:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3OtLjOeo1/Listeners', 12:23:41 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 12:23:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:41 INFO - 'USER': 'cltbld', 12:23:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:41 INFO - 'XPC_FLAGS': '0x0', 12:23:41 INFO - 'XPC_SERVICE_NAME': '0', 12:23:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:41 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:41 INFO - Cleaning up... 12:23:41 INFO - Return code: 0 12:23:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:41 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 0x10fb8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd85982d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f969d30>, '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 0x10f88fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f95ebe8>, '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.QzfUAG9JSe/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.b3OtLjOeo1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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:41 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:41 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:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QzfUAG9JSe/Render', 12:23:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:41 INFO - 'HOME': '/Users/cltbld', 12:23:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:41 INFO - 'LOGNAME': 'cltbld', 12:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:41 INFO - 'MOZ_NO_REMOTE': '1', 12:23:41 INFO - 'NO_EM_RESTART': '1', 12:23:41 INFO - 'PAGER': '/bin/cat', 12:23:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:41 INFO - 'PWD': '/builds/slave/test', 12:23:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:41 INFO - 'SHELL': '/bin/bash', 12:23:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3OtLjOeo1/Listeners', 12:23:41 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 12:23:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:41 INFO - 'USER': 'cltbld', 12:23:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:41 INFO - 'XPC_FLAGS': '0x0', 12:23:41 INFO - 'XPC_SERVICE_NAME': '0', 12:23:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:42 INFO - Downloading/unpacking psutil>=3.1.1 12:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:23:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:46 INFO - Installing collected packages: psutil 12:23:46 INFO - Running setup.py install for psutil 12:23:46 INFO - building 'psutil._psutil_osx' extension 12:23:46 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:46 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:46 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:47 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:47 INFO - building 'psutil._psutil_posix' extension 12:23:47 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:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:23:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:23:47 INFO - ^ 12:23:47 INFO - 1 warning generated. 12:23:47 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:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:47 INFO - Successfully installed psutil 12:23:47 INFO - Cleaning up... 12:23:47 INFO - Return code: 0 12:23:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:23:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:47 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 0x10fb8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd85982d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f969d30>, '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 0x10f88fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f95ebe8>, '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.QzfUAG9JSe/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.b3OtLjOeo1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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:47 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:47 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:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QzfUAG9JSe/Render', 12:23:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:47 INFO - 'HOME': '/Users/cltbld', 12:23:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:47 INFO - 'LOGNAME': 'cltbld', 12:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:47 INFO - 'MOZ_NO_REMOTE': '1', 12:23:47 INFO - 'NO_EM_RESTART': '1', 12:23:47 INFO - 'PAGER': '/bin/cat', 12:23:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:47 INFO - 'PWD': '/builds/slave/test', 12:23:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:47 INFO - 'SHELL': '/bin/bash', 12:23:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3OtLjOeo1/Listeners', 12:23:47 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 12:23:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:47 INFO - 'USER': 'cltbld', 12:23:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:47 INFO - 'XPC_FLAGS': '0x0', 12:23:47 INFO - 'XPC_SERVICE_NAME': '0', 12:23:47 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 mozsystemmonitor==0.3 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:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:23:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:23:50 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:50 INFO - Installing collected packages: mozsystemmonitor 12:23:50 INFO - Running setup.py install for mozsystemmonitor 12:23:50 INFO - Successfully installed mozsystemmonitor 12:23:50 INFO - Cleaning up... 12:23:50 INFO - Return code: 0 12:23:50 INFO - Installing jsonschema==2.5.1 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', '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 0x10fb8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd85982d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f969d30>, '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 0x10f88fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f95ebe8>, '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.QzfUAG9JSe/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.b3OtLjOeo1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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', 'jsonschema==2.5.1'] 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 jsonschema==2.5.1 12:23:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QzfUAG9JSe/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.b3OtLjOeo1/Listeners', 12:23:50 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 - Downloading/unpacking jsonschema==2.5.1 12:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:23:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 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:53 INFO - Downloading functools32-3.2.3-2.tar.gz 12:23:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:23:54 INFO - warning: no files found matching '*.txt' 12:23:54 INFO - no previously-included directories found matching 'build' 12:23:54 INFO - no previously-included directories found matching 'dist' 12:23:54 INFO - no previously-included directories found matching '.git*' 12:23:54 INFO - Installing collected packages: jsonschema, functools32 12:23:54 INFO - Running setup.py install for functools32 12:23:54 INFO - warning: no files found matching '*.txt' 12:23:54 INFO - no previously-included directories found matching 'build' 12:23:54 INFO - no previously-included directories found matching 'dist' 12:23:54 INFO - no previously-included directories found matching '.git*' 12:23:54 INFO - Successfully installed jsonschema functools32 12:23:54 INFO - Cleaning up... 12:23:54 INFO - Return code: 0 12:23:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:23:54 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:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:54 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:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:54 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 0x10fb8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd85982d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f969d30>, '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 0x10f88fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f95ebe8>, '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.QzfUAG9JSe/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.b3OtLjOeo1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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:54 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:54 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:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QzfUAG9JSe/Render', 12:23:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:54 INFO - 'HOME': '/Users/cltbld', 12:23:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:54 INFO - 'LOGNAME': 'cltbld', 12:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:54 INFO - 'MOZ_NO_REMOTE': '1', 12:23:54 INFO - 'NO_EM_RESTART': '1', 12:23:54 INFO - 'PAGER': '/bin/cat', 12:23:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:54 INFO - 'PWD': '/builds/slave/test', 12:23:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:54 INFO - 'SHELL': '/bin/bash', 12:23:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3OtLjOeo1/Listeners', 12:23:54 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 12:23:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:54 INFO - 'USER': 'cltbld', 12:23:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:54 INFO - 'XPC_FLAGS': '0x0', 12:23:54 INFO - 'XPC_SERVICE_NAME': '0', 12:23:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:23:54 INFO - Cleaning up... 12:23:54 INFO - Return code: 0 12:23:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:23:54 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:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:54 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:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:54 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 0x10fb8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd85982d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f969d30>, '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 0x10f88fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f95ebe8>, '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.QzfUAG9JSe/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.b3OtLjOeo1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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:54 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:54 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:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QzfUAG9JSe/Render', 12:23:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:54 INFO - 'HOME': '/Users/cltbld', 12:23:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:54 INFO - 'LOGNAME': 'cltbld', 12:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:54 INFO - 'MOZ_NO_REMOTE': '1', 12:23:54 INFO - 'NO_EM_RESTART': '1', 12:23:54 INFO - 'PAGER': '/bin/cat', 12:23:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:54 INFO - 'PWD': '/builds/slave/test', 12:23:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:54 INFO - 'SHELL': '/bin/bash', 12:23:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3OtLjOeo1/Listeners', 12:23:54 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 12:23:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:54 INFO - 'USER': 'cltbld', 12:23:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:54 INFO - 'XPC_FLAGS': '0x0', 12:23:54 INFO - 'XPC_SERVICE_NAME': '0', 12:23:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:54 INFO - Downloading/unpacking 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:57 INFO - Downloading blobuploader-1.2.4.tar.gz 12:23:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:23:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:23:57 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:57 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:57 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:57 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:57 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:57 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:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:23:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:58 INFO - Downloading docopt-0.6.1.tar.gz 12:23:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:23:58 INFO - Installing collected packages: blobuploader, requests, docopt 12:23:58 INFO - Running setup.py install for blobuploader 12:23:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:23:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:23:58 INFO - Running setup.py install for requests 12:23:59 INFO - Running setup.py install for docopt 12:23:59 INFO - Successfully installed blobuploader requests docopt 12:23:59 INFO - Cleaning up... 12:23:59 INFO - Return code: 0 12:23:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:59 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 0x10fb8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd85982d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f969d30>, '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 0x10f88fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f95ebe8>, '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.QzfUAG9JSe/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.b3OtLjOeo1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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:59 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:59 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:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QzfUAG9JSe/Render', 12:23:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:59 INFO - 'HOME': '/Users/cltbld', 12:23:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:59 INFO - 'LOGNAME': 'cltbld', 12:23:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:59 INFO - 'MOZ_NO_REMOTE': '1', 12:23:59 INFO - 'NO_EM_RESTART': '1', 12:23:59 INFO - 'PAGER': '/bin/cat', 12:23:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:59 INFO - 'PWD': '/builds/slave/test', 12:23:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:59 INFO - 'SHELL': '/bin/bash', 12:23:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3OtLjOeo1/Listeners', 12:23:59 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 12:23:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:59 INFO - 'USER': 'cltbld', 12:23:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:59 INFO - 'XPC_FLAGS': '0x0', 12:23:59 INFO - 'XPC_SERVICE_NAME': '0', 12:23:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:59 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Wcc43S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:24:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-kFfgS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:24:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ypUICv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:24:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-nmWkL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:24:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ExfXm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:24:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-J4pXM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:24:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-zyz6Kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:24:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-lwHTbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:24:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-2oCQEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:24:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-1FJvGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:24:01 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-EYn_Wh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:24:01 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-kLUb9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:24:01 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-2afwC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:24:01 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-rt8iD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:24:01 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-lTwbQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:24:01 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-i5iY4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:24:01 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Bidpwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:24:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:24:01 INFO - Running setup.py install for manifestparser 12:24:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:24:01 INFO - Running setup.py install for mozcrash 12:24:02 INFO - Running setup.py install for mozdebug 12:24:02 INFO - Running setup.py install for mozdevice 12:24:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:24:02 INFO - Running setup.py install for mozfile 12:24:02 INFO - Running setup.py install for mozhttpd 12:24:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:24:02 INFO - Running setup.py install for mozinfo 12:24:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:24:02 INFO - Running setup.py install for mozInstall 12:24:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:24:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:24:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:24:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:24:02 INFO - Running setup.py install for mozleak 12:24:02 INFO - Running setup.py install for mozlog 12:24:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:24:03 INFO - Running setup.py install for moznetwork 12:24:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:24:03 INFO - Running setup.py install for mozprocess 12:24:03 INFO - Running setup.py install for mozprofile 12:24:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:24:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:24:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:24:03 INFO - Running setup.py install for mozrunner 12:24:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:24:03 INFO - Running setup.py install for mozscreenshot 12:24:03 INFO - Running setup.py install for moztest 12:24:04 INFO - Running setup.py install for mozversion 12:24:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:24:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:24:04 INFO - Cleaning up... 12:24:04 INFO - Return code: 0 12:24:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:24:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:24:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:04 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 0x10fb8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd85982d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f969d30>, '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 0x10f88fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f95ebe8>, '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.QzfUAG9JSe/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.b3OtLjOeo1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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:04 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:04 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:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QzfUAG9JSe/Render', 12:24:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:24:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:24:04 INFO - 'HOME': '/Users/cltbld', 12:24:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:24:04 INFO - 'LOGNAME': 'cltbld', 12:24:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:04 INFO - 'MOZ_NO_REMOTE': '1', 12:24:04 INFO - 'NO_EM_RESTART': '1', 12:24:04 INFO - 'PAGER': '/bin/cat', 12:24:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:24:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:04 INFO - 'PWD': '/builds/slave/test', 12:24:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:24:04 INFO - 'SHELL': '/bin/bash', 12:24:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3OtLjOeo1/Listeners', 12:24:04 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 12:24:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:24:04 INFO - 'USER': 'cltbld', 12:24:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:24:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:24:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:04 INFO - 'XPC_FLAGS': '0x0', 12:24:04 INFO - 'XPC_SERVICE_NAME': '0', 12:24:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:24:04 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-9rtKRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:24:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:24:04 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-qhwCIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:24:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:24:04 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-opIEjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:24:04 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:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:24:04 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-yPQBnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:24:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:24:04 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-5wlaCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:24:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:24:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-rFI7ED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:24:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:24:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-fBXDEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:24:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:24:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-1Jy7uI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:24:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:24:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-tWnY1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:24:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:24:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-G3mwlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:24:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:24:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-_DKpvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:24:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:24:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-iicP0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:24:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:24:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-s3n56q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:24:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:24:05 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ciLcoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:24:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:24:06 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-a7Fcw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:24:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:24:06 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ntQzZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:24:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:24:06 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-yWB39X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:24:06 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:06 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:06 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:06 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:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:24:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:24:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:24:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:24:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:24:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:24:08 INFO - Downloading blessings-1.6.tar.gz 12:24:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:24:09 INFO - Installing collected packages: blessings 12:24:09 INFO - Running setup.py install for blessings 12:24:09 INFO - Successfully installed blessings 12:24:09 INFO - Cleaning up... 12:24:09 INFO - Return code: 0 12:24:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:24:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:24:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:24:09 INFO - Reading from file tmpfile_stdout 12:24:09 INFO - Current package versions: 12:24:09 INFO - blessings == 1.6 12:24:09 INFO - blobuploader == 1.2.4 12:24:09 INFO - docopt == 0.6.1 12:24:09 INFO - functools32 == 3.2.3-2 12:24:09 INFO - jsonschema == 2.5.1 12:24:09 INFO - manifestparser == 1.1 12:24:09 INFO - mozInstall == 1.12 12:24:09 INFO - mozcrash == 1.0 12:24:09 INFO - mozdebug == 0.1 12:24:09 INFO - mozdevice == 0.50 12:24:09 INFO - mozfile == 1.2 12:24:09 INFO - mozhttpd == 0.7 12:24:09 INFO - mozinfo == 0.9 12:24:09 INFO - mozleak == 0.1 12:24:09 INFO - mozlog == 3.4 12:24:09 INFO - moznetwork == 0.27 12:24:09 INFO - mozprocess == 0.25 12:24:09 INFO - mozprofile == 0.28 12:24:09 INFO - mozrunner == 6.13 12:24:09 INFO - mozscreenshot == 0.1 12:24:09 INFO - mozsystemmonitor == 0.3 12:24:09 INFO - moztest == 0.8 12:24:09 INFO - mozversion == 1.4 12:24:09 INFO - psutil == 3.1.1 12:24:09 INFO - requests == 1.2.3 12:24:09 INFO - wsgiref == 0.1.2 12:24:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:24:09 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:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:09 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:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:09 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 0x10fb8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd85982d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f969d30>, '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 0x10f88fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f95ebe8>, '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.QzfUAG9JSe/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.b3OtLjOeo1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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:09 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:09 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:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QzfUAG9JSe/Render', 12:24:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:24:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:24:09 INFO - 'HOME': '/Users/cltbld', 12:24:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:24:09 INFO - 'LOGNAME': 'cltbld', 12:24:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:09 INFO - 'MOZ_NO_REMOTE': '1', 12:24:09 INFO - 'NO_EM_RESTART': '1', 12:24:09 INFO - 'PAGER': '/bin/cat', 12:24:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:24:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:09 INFO - 'PWD': '/builds/slave/test', 12:24:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:24:09 INFO - 'SHELL': '/bin/bash', 12:24:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3OtLjOeo1/Listeners', 12:24:09 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 12:24:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:24:09 INFO - 'USER': 'cltbld', 12:24:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:24:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:24:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:09 INFO - 'XPC_FLAGS': '0x0', 12:24:09 INFO - 'XPC_SERVICE_NAME': '0', 12:24:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:24:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:09 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:09 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:09 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:09 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:09 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:09 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:09 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:09 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:09 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:09 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:09 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:09 INFO - Cleaning up... 12:24:09 INFO - Return code: 0 12:24:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:24:09 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:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:24:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:09 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:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:24:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:24:09 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 0x10fb8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadd85982d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f969d30>, '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 0x10f88fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f95ebe8>, '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.QzfUAG9JSe/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.b3OtLjOeo1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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:09 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:09 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:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QzfUAG9JSe/Render', 12:24:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:24:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:24:09 INFO - 'HOME': '/Users/cltbld', 12:24:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:24:09 INFO - 'LOGNAME': 'cltbld', 12:24:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:09 INFO - 'MOZ_NO_REMOTE': '1', 12:24:09 INFO - 'NO_EM_RESTART': '1', 12:24:09 INFO - 'PAGER': '/bin/cat', 12:24:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:24:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:09 INFO - 'PWD': '/builds/slave/test', 12:24:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:24:09 INFO - 'SHELL': '/bin/bash', 12:24:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3OtLjOeo1/Listeners', 12:24:09 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 12:24:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:24:09 INFO - 'USER': 'cltbld', 12:24:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:24:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:24:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:09 INFO - 'XPC_FLAGS': '0x0', 12:24:09 INFO - 'XPC_SERVICE_NAME': '0', 12:24:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:24:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:24:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:24:10 INFO - Cleaning up... 12:24:10 INFO - Return code: 0 12:24:10 INFO - Running post-action listener: _resource_record_post_action 12:24:10 INFO - Running post-action listener: _start_resource_monitoring 12:24:10 INFO - Starting resource monitoring. 12:24:10 INFO - [mozharness: 2017-04-15 19:24:10.181368Z] Finished create-virtualenv step (success) 12:24:10 INFO - [mozharness: 2017-04-15 19:24:10.182303Z] Running install step. 12:24:10 INFO - Running pre-action listener: _resource_record_pre_action 12:24:10 INFO - Running main action method: install 12:24:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:24:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:24:10 INFO - Reading from file tmpfile_stdout 12:24:10 INFO - Detecting whether we're running mozinstall >=1.0... 12:24:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:24:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:24:10 INFO - Reading from file tmpfile_stdout 12:24:10 INFO - Output received: 12:24:10 INFO - Usage: mozinstall [options] installer 12:24:10 INFO - Options: 12:24:10 INFO - -h, --help show this help message and exit 12:24:10 INFO - -d DEST, --destination=DEST 12:24:10 INFO - Directory to install application into. [default: 12:24:10 INFO - "/builds/slave/test"] 12:24:10 INFO - --app=APP Application being installed. [default: firefox] 12:24:10 INFO - mkdir: /builds/slave/test/build/application 12:24:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:24:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:24:30 INFO - Reading from file tmpfile_stdout 12:24:30 INFO - Output received: 12:24:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:24:30 INFO - Running post-action listener: _resource_record_post_action 12:24:30 INFO - [mozharness: 2017-04-15 19:24:30.614896Z] Finished install step (success) 12:24:30 INFO - [mozharness: 2017-04-15 19:24:30.615041Z] Running run-tests step. 12:24:30 INFO - Running pre-action listener: _resource_record_pre_action 12:24:30 INFO - Running main action method: run_tests 12:24:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:24:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:24:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:24:30 INFO - 2017-04-15 12:24:30.629 screenresolution[1631:8033] starting screenresolution argv=screenresolution get 12:24:30 INFO - 2017-04-15 12:24:30.632 screenresolution[1631:8033] Display 0: 1600x1200x32@60 12:24:30 INFO - 2017-04-15 12:24:30.638 screenresolution[1632:8036] starting screenresolution argv=screenresolution list 12:24:30 INFO - Available Modes on Display 0 12:24:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:24:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:24:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:24:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:24:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:24:30 INFO - Intel Iris: 12:24:30 INFO - Chipset Model: Intel Iris 12:24:30 INFO - Type: GPU 12:24:30 INFO - Bus: Built-In 12:24:30 INFO - VRAM (Dynamic, Max): 1536 MB 12:24:30 INFO - Vendor: Intel (0x8086) 12:24:30 INFO - Device ID: 0x0a2e 12:24:30 INFO - Revision ID: 0x0009 12:24:30 INFO - Displays: 12:24:30 INFO - Display: 12:24:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:24:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:24:30 INFO - Display Serial Number: 1600x1200 60 12:24:30 INFO - Main Display: Yes 12:24:30 INFO - Mirror: Off 12:24:30 INFO - Online: Yes 12:24:30 INFO - Rotation: Supported 12:24:30 INFO - Return code: 0 12:24:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:24:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:24:30 INFO - Python 2.7.3 12:24:30 INFO - Return code: 0 12:24:30 INFO - grabbing minidump binary from tooltool 12:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:24:30 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f969d30>, '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 0x10f88fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f95ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:24:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:24:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:24:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:24:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:24:30 INFO - Return code: 0 12:24:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:24:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:24:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:24:31 INFO - ENV: RUST_BACKTRACE is now 1 12:24:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:24:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:24:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0318 --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:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QzfUAG9JSe/Render', 12:24:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:24:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:24:31 INFO - 'HOME': '/Users/cltbld', 12:24:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:24:31 INFO - 'LOGNAME': 'cltbld', 12:24:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:24:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:31 INFO - 'MOZ_NO_REMOTE': '1', 12:24:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:24:31 INFO - 'NO_EM_RESTART': '1', 12:24:31 INFO - 'PAGER': '/bin/cat', 12:24:31 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:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:31 INFO - 'PWD': '/builds/slave/test', 12:24:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:24:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:24:31 INFO - 'RUST_BACKTRACE': '1', 12:24:31 INFO - 'SHELL': '/bin/bash', 12:24:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3OtLjOeo1/Listeners', 12:24:31 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 12:24:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:24:31 INFO - 'USER': 'cltbld', 12:24:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:24:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:31 INFO - 'XPC_FLAGS': '0x0', 12:24:31 INFO - 'XPC_SERVICE_NAME': '0', 12:24:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:24:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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:31 INFO - mozversion application_buildid: 20170415110806 12:24:31 INFO - mozversion application_changeset: ce69b6e1773e9e0d0a190ce899f34b1658e66ca4 12:24:31 INFO - mozversion application_display_name: Nightly 12:24:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:24:31 INFO - mozversion application_name: Firefox 12:24:31 INFO - mozversion application_remotingname: firefox 12:24:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:24:31 INFO - mozversion application_vendor: Mozilla 12:24:31 INFO - mozversion application_version: 55.0a1 12:24:31 INFO - mozversion platform_buildid: 20170415110806 12:24:31 INFO - mozversion platform_changeset: ce69b6e1773e9e0d0a190ce899f34b1658e66ca4 12:24:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:24:31 INFO - mozversion platform_version: 55.0a1 12:24:31 INFO - using testdate: 1492284271 12:24:31 INFO - actual date: 1492284271 12:24:31 INFO - starting webserver on 'localhost:49243' 12:24:31 INFO - SUITE-START | Running 2 tests 12:24:31 INFO - TEST-START | dromaeo_css 12:24:31 INFO - operating with platform_type : mac_ 12:24:31 INFO - Initialising browser for dromaeo_css test... 12:24:31 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmphnCqGv/profile http://localhost:49243/getInfo.html) 12:24:34 INFO - PID 1642 | 2017-04-15 12:24:34.072 plugin-container[1645:8233] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 12:24:34 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:34 INFO - PID 1642 | 2017-04-15 12:24:34.075 plugin-container[1645:8233] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 12:24:34 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:34 INFO - PID 1642 | 2017-04-15 12:24:34.075 plugin-container[1645:8233] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:24:34 INFO - PID 1642 | __metrics Screen width/height:1600/1200 12:24:34 INFO - PID 1642 | colorDepth:24 12:24:34 INFO - PID 1642 | Browser inner width/height: 1024/689 12:24:34 INFO - PID 1642 | __metrics 12:24:34 ERROR - PID 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 12:24:35 INFO - TEST-INFO | 1642: exit 0 12:24:35 INFO - Browser initialized. 12:24:35 INFO - Running cycle 1/1 for dromaeo_css test... 12:24:35 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmphnCqGv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:24:37 INFO - PID 1646 | 2017-04-15 12:24:37.619 plugin-container[1647:8365] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 12:24:37 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:37 INFO - PID 1646 | 2017-04-15 12:24:37.619 plugin-container[1647:8365] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 12:24:37 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:37 INFO - PID 1646 | 2017-04-15 12:24:37.619 plugin-container[1647:8365] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:24:47 INFO - PID 1646 | RSS: Main: 237215744 12:24:47 INFO - PID 1646 | 12:27:50 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 12:27:52 INFO - PID 1646 | RSS: Main: 252964864 12:27:52 INFO - PID 1646 | 12:29:35 INFO - PID 1646 | ************************* 12:29:35 INFO - PID 1646 | A coding exception was thrown and uncaught in a Task. 12:29:35 INFO - PID 1646 | 12:29:35 ERROR - PID 1646 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:29:35 INFO - PID 1646 | Full stack: 12:29:35 INFO - PID 1646 | ************************* 12:29:35 INFO - PID 1646 | ************************* 12:29:35 INFO - PID 1646 | A coding exception was thrown and uncaught in a Task. 12:29:35 INFO - PID 1646 | 12:29:35 ERROR - PID 1646 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:29:35 INFO - PID 1646 | Full stack: 12:29:35 INFO - PID 1646 | ************************* 12:29:35 INFO - PID 1646 | ************************* 12:29:35 INFO - PID 1646 | A coding exception was thrown and uncaught in a Task. 12:29:35 INFO - PID 1646 | 12:29:35 ERROR - PID 1646 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:29:35 INFO - PID 1646 | Full stack: 12:29:35 INFO - PID 1646 | ************************* 12:29:35 INFO - PID 1646 | ************************* 12:29:35 INFO - PID 1646 | A coding exception was thrown and uncaught in a Task. 12:29:35 INFO - PID 1646 | 12:29:35 ERROR - PID 1646 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:29:35 INFO - PID 1646 | Full stack: 12:29:35 INFO - PID 1646 | ************************* 12:30:54 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 12:30:55 INFO - PID 1646 | RSS: Main: 258547712 12:30:55 INFO - PID 1646 | 12:33:56 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 12:33:57 INFO - PID 1646 | RSS: Main: 267567104 12:33:57 INFO - PID 1646 | 12:36:59 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 12:36:59 INFO - PID 1646 | RSS: Main: 261357568 12:36:59 INFO - PID 1646 | 12:40:02 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 12:40:03 INFO - PID 1646 | RSS: Main: 270213120 12:40:03 INFO - PID 1646 | 12:43:04 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 12:43:05 INFO - PID 1646 | RSS: Main: 264720384 12:43:05 INFO - PID 1646 | 12:43:05 INFO - PID 1646 | __start_tp_report 12:43:05 INFO - PID 1646 | _x_x_mozilla_page_load 12:43:05 INFO - PID 1646 | _x_x_mozilla_page_load_details 12:43:05 INFO - PID 1646 | |i|pagename|runs| 12:43:05 INFO - PID 1646 | |0;dojo.html;3299.6865297796703;3360;3561.839717212725;3564.982175089124;3616.7649102808314;5312.89374212516;6108.584917381041;6212.751489940409;6216.720247588858;6286.3399343069;6186;6203.255609326887;6204.875902481947;6219.315875253718;6282.717277722502;5484.588655850807;5516.221700710981;5518.420565840569;5522.723863806814;5523.2543606613;5512.614117011811;5527.723613819313;5583.469570390806;5605.915911261334;5671.035923892948;5427.348718153808;5474.972625136888;5526.778928842882;5538.47384498472;5591.41290164533;5492.066348720727;5502.0921547944545;5505.3393592768725;5513.283273174502;5559.555235581144;5382.8923421531745;5443.700596467193;5444.891102177974;5458.317710286215;5479.232907392969;5060.74696265189;6021.548383871227;6164.568480206387;6181.041938499539;6241.87516249677;6715.764948226837;6735.360140786617;6769.018492318586;6773.085633439521;6776.2546119926765;5619.213310136584;6855.622940738257;6860.0738900248325;6886.931130688729;6907.205671347809;6249.281332646804;6300.747970081191;6303.936960630427;6337.524685648595;6340.556161068773;6888.242293347727;6937.618430986344;6984.546004509691;7001.754938577125;7003.264657211022;6446.967765161145;6567.4417674497245;6584.078229047937;6593.274739778621;6600.60396376219;6842.384185423236;6847.623380714009;6864.7940561782225;6881.449484041264;6883.8967415488805;6489.415952564292;6571.145751052428;6641.542629227217;6681.832954176184;6790.287019862942;6707.798766037026;6784.491163162783;6802.3537763912345;6812.4550035997;6831.59010459374;5376.838694839083;5387.434319396407;5406.053940560386;5410.1343784995;5456.563474921997;4736.64475164364;4753.572178503951;4761.714297142183;4763.04739052191;4815.831445899376;6180.443760061616;6326.810195694136;6338.556301058881;6347.174867267226;6363.33185015576;6802.523823332418;6866.279040700754;6882.5870447773295;6883.070785444002;6885.758998435031;6238.251409830849;6263.812085637347;6298.968505157445;6321.620702757849;6356.45970092538;5981.252343457068;6033.668148251888;6038.064100064497;6077.908831367533;6082.878342433127;6931.722731090801;6977.79066628002;6986.615736134561;7000.649967501707;7007.089078419773;6557.1147895035465;6742.422358704109;6891.414229790524;6894.276101009322;6903.5167538271835;6788.76239331621;6812.25065242813;6945.930540694731;6984.301569843178;7020.333068358498;6729.024291477806;6739.123913891162;6754.223264324469;6754.932450675627;6758.155230596176;6516.283208847118;6549.836254093686;6793.864122717617;6864;6894.689738961659;5535.252740880091;6544.8363790905605;6618.470522358296;6625.337466253336;6630.900536492053;6576.967115164393;6587.934120658732;6604.735810567716;6617.966910165418;6631.502637302126;5216.713080780593;6305;6336.271328797247;6451.451626611791;6477.578957367756;6408.07082972957;6535.150430444012;6584.176977877765;6613.008048792527;6634.701438435185;5595.216669666332;6301.369863013662;6454.128692626436;6466.159399278064;6470.870582588228 12:43:05 INFO - PID 1646 | |1;ext.html;791.1771757372333;833.3333333333331;1075.634284343323;1283.4352884730718;1312.7046414556726;38074.809625951864;38386.040348991286;38564.61435385645;38678;38692.22615547691;38340.04149896254;38666;38717.45130194789;38738.41892371617;38767.612323876754;8291.212334828211;8519.659213631447;8522;8528.957355213215;8529.872051919227;8518.190771876662;8639.049704532496;8649;8651.567421628924;8675.826483470359;8046.396520260974;8579.656813727443;8655.567221638925;8656;8672.8699069514;7276.417886569088;8542.530160841152;8615.095414981432;8630.352723545759;8633.95683021581;8457.703980360659;8479.915200848036;8486.448380855225;8494.70268540598;8510.829783404297;37852.81073594642;37900.43149352762;37979.430308545394;38023.66917157914;38077.23845523074;3650.7444478886487;3691.2063906260014;3692.1877187018813;3695.611960744137;3696.9075773105887;24140.396490087714;24321.905514251903;24344.904479298475;24401.87799061011;24464;3053.3282677811067;3138.8901388451523;3139.748820094387;3141.4345417824784;3149.9762577162287;303035;303199;303569;303578;303944;39381;40135.59864401319;48938.510614894105;49029.75485122623;49258.01483970372;50715;50811.745941270055;52017.219741703906;53677.92644147173;53765.92468150615;14415.92792036054;14514.274286285643;14518;14534.418623254951;14562.708745825024;11561.537538498365;11669.35818529989;11676.591319303783;11678.941605291919;11699.356535390465;13923.30383480822;13983.86016139846;13985.230812305217;14010.299485025707;14019;5034.773435195425;5570.916436253459;6078.817635470943;6102.542309326729;6130.080487926757;975.2831668723483;1027.2347101409498;1032.385730490357;1038.4288641247283;1079.2553138334524;23747.168849090198;24717.25848224556;24825.379365515644;24917.377065573506;24945.501089978097;1125.3810404277615;1127.115214556569;1162.314234601589;1166.445938179375;1171.4377098992532;12626.305553194663;12775.61673149807;12776.488940442465;12784.74430511403;12795.424205910756;315754;318350;319112.2133168004;319586;320798;55496;55727.721361392934;55856;55856.72071639697;56098.439015610136;1489.9255037248095;1534.0565552185326;1570.2776722707683;1575.7715131305897;1578.5974576483154;20145.496362591053;24187;24406.755932440446;24488.75511244865;24700.7649617522;38716.6128338713;38847.61152388553;38861.22277554376;38896.80551597224;38936;289033;292584;292831;293816;294719;35271.647283526836;35394.29211415809;35427.82286088553;36180.4572931401;37172.69895553643;45192.77403612961;45675.77162114301;46065;46216.84457888579;46221;29535.26161845885;29781.957631482794;30778.538321925636;31522.369552609278;31871.203219919687;30790.53814192744;31215.59529821118;31641.8417907909;31677.84161079272;31723;36298.8185059073;36975;37042;37213;37403.81298093492;31164.220894477818;31400.52899206468;31579.84210078935;31738.20654483564;32020.35959280848;19404.90297548503;23158.1894633687;24638.01447942062;24851.87574062118;24877.751222487543 12:43:05 INFO - PID 1646 | |2;jquery.html;386.35671606774724;430.1075268817205;439.59776804224157;441.4768499328296;466.2074473395229;21057.68413473797;21257.618254813464;21610.24364147253;21782.89108554455;22034.228801991914;19365.419037428863;21101.417393695498;21346.89326553365;21717.565648687058;21887.233946811866;6052.546059045567;6222.9688851555675;6241.875162496747;6248.094026366174;6264.060390941372;4750.501197374278;6018.8194354169445;6078.088286757;6166.7841625543115;6258.499320054385;6109.327973922865;6183.536234782387;6186.690665466731;6201.162843016203;6205.410486003822;6060.272767267934;6172.197614310134;6204.534659900481;6207.037909124116;6209.3790620938;5468.207109969072;5523.613347065707;5551.694656793875;5560.388357280696;5659.915101273484;23228.65157022646;27307;27374.17877463679;28131.578026329622;28812.711872881424;2183.9235626753148;2256.650219216024;2259.0624890670515;2267.4331417145713;2269.1830940861278;17655.852369595934;17727.847689900245;17734.733978990327;18313.542161446072;18346.633067338713;2184.2682701295084;2187.671849222613;2232.0625337358188;2233.642617181243;2238.5746708125403;159843;167380;168314;168562;169200.15399923045;10592.311499752492;12681.49274029047;12925.676858078623;12960.611181664377;13283.409735101755;16518.75221871673;16713.916430418012;16720;16752.664946701;16757;8780.209781119602;8916.73249802494;9110.908890911007;9199.03410141939;9222.40054396462;7125.25184855593;8695.826083478294;8745.737627871174;8872.423292485968;8875.600597973107;7809.258120478546;7830.451868369273;7859.292663660298;7964;7972.322352599978;2158.48196432855;2162.5566758814407;2162.8269738421204;2164.718586583734;2314.0396735354975;912.5893347993297;927.1655510041018;960.9711708648751;1007.9344842585208;1017.3438132404558;18821.81178188228;19701.408957731004;20075.39773806788;20556.45826563014;21000.264990725558;982.9803403931882;1011.3628414099071;1017.8269694152012;1018.3889666200369;1018.9962886556735;9959.452230127268;10651.573937042587;10702.022979621577;10710.89289107099;10746.677599672023;512743.4362828162;577126;581896;581905;592867;129086;134069.3296533511;138977;139068;141899.2905035489;1178.8113901775675;1213.1568559850818;1269.2194300505123;1271.5168236070233;1271.9364031798373;21173.36479905544;21256.149754009668;21318.253861114787;21668.45828854291;21788.801615911278;57493.13760293515;57997;58908;59791.70104149451;60297.69851150716;54169.3749187525;79485.01287467628;80776.19223807687;82326;82825.58587207025;8572.785680358042;8605.311575074104;8691.826163476822;8937.910620893968;8943.821123577622;11274;11682.415879206175;13251.072424930162;13612.591622251348;13927.58217253484;9872.45701486425;11697.356645384585;11770.058395328524;11816.645500634995;12004.879951200377;9044.864327034968;9856.31005829585;9979.60081596728;10283.640072597424;10889.401082940514;11088.279261847954;11484.425778711198;11663.708407289885;11698;11876.821847672465;8537.786555335917;8718.782030449036;8783.341249406192;8910.198082172636;8954.328425368254;10161.695149145537;10832.133429325477;11261.887381126084;11786.410679466164;11812 12:43:05 INFO - PID 1646 | |3;mootools.html;1446.233496246989;1490.0761527852733;1494.3648949196588;1510.667653116314;1532.969340613188;11583.53665853366;11990.880091199086;12029.939850300736;12051.698707532316;12351.197172183802;11906.64280071597;12036.277823330607;12066.913977742002;12107;12112.757744845097;2532.5314816758914;2583.5220484210386;2585.650937123483;2586.8318559293584;2589.4173810892585;2485.875706214691;2540.0855691950883;2546.121588052119;2561.602951542514;2565.3586603349163;2475.789557887582;2551.9489610207784;2569.5760199567144;2573.6782902137234;2574.2663340947834;2485.6768620079365;2519.3323769201174;2537.175417989161;2539.3270783242456;2555.131255373182;2505.7744802967823;2533.6199570064455;2533.6832895888015;2535.125381743314;2540.847549147057;11677.240979336317;11827.408629568487;11887.94056029723;11901.88098119025;11941.164118511708;6591.274959754423;6836.829079273009;6843.965780171067;6845.589264644087;6855.4172895303955;3618;4129.2773764591075;4320.546342634041;4407;4412.382266482695;1232.7226374065854;1252.278117588316;1301.8958483321312;1306.45129045801;1308.4439113376777;16350;16504.917475412665;16642.084685342303;16755.66488670232;16945.745813812802;5333.599980001515;5336.9859726652585;5673.489385955201;5824.359320474744;5896.439838215363;12143.757124857453;12291.75416491683;12344;12424.689382765504;12443.564475243324;6680.0647909291765;6714.227863795629;6719.932800672029;6726.058351830748;6768.56004359715;6022.519507248383;6141.201643786369;6206.317305096436;6207.255129384503;6284.528660350401;4341.717788343748;4468.664850136252;4503.099380123988;4510.255807791743;4521.728696278234;1234.1484375780683;1302.2512055567897;1316.822228999092;1330.60081975407;1333.200079952021;756.916739158692;763.4655740981335;783.4085265624417;793.7817100297349;827.0984626756864;6646.60120392778;6994.300569942965;7012.298770122947;7038.577685338896;7070.222275549685;760.9467337286447;885.1900511032462;919.7424721078108;952.7618095476131;962.6630679262203;4123.195976784558;4250.234957707642;4379.781010949439;4438.866833994985;4507.053518761097;14491.92754036223;15069;15224.619384515476;15227;15239.923800380928;14966;15159.09045457276;15165.393384264506;15185.544433667008;15479.22603869802;7635.923640763522;7903.091144518339;8240.104775901576;8347.833043339098;8477.279431248413;5619.80330688424;6715.127033485615;6764.864702706017;7142.5000249982;7230.89153662706;10068.798624027331;10776.838347424638;13490.662733431744;13981.300934953008;14088.36602352917;10595.523201455799;13731.725365492435;13857.514986975408;14039.298035098409;14231.573052808433;9527.237820974444;9625.855612165964;9666.903330966881;9713.805723885624;9773.267004974514;10797.56809727602;10883.129349652167;10911;10923.453827308762;10925.289856159325;9040.276777857887;9274.489903055397;9292.488913109573;9422.764430889283;9437.575309111244;9467.905320946702;9508.85736713936;9527.761805954906;9543.9045609543;9683.854742178732;8101.716439924574;8323.375746818892;8408.53753043564;8420.87368689458;8422.199891010345;5561.777528898961;6070.666113363807;6121.418465245794;6222.968885155546;6235.781747638811;5701.201831743476;6143.170671959407;6199.605088855152;6251.6874156292915;6331.398517140864 12:43:05 INFO - PID 1646 | |4;prototype.html;352.43786161073086;399.1239229890388;401.67865707434044;408.6322309921072;422.3601244115165;4838.758062096898;6228.318354044417;6303.55875088744;6323.936760632392;6347.5556711030185;5962.552808539355;6005.987994022008;6118.418750218733;6194.938050619492;6227.28386235583;4380.430544029272;4454.754988475633;4601.309803529472;4605;4629.754569034016;4567.13224487348;4578.679492435531;4578.862634120982;4581.656375771814;4589.691937797728;4538.614217791492;4554.908901821962;4561.635069194473;4570.382998295221;4591.678582499209;4377.22296355456;4550.476695180047;4557.384753058328;4557.908841823178;4570.428696412949;3278.1804548862783;3611.873584424559;3716.479692843004;3927.3323534999117;3938.9803050984847;5753.367129615739;5871.854988277288;6102;6108.388166933981;6135.478484328837;4373.825046998117;5425.240466334716;5426.348838139409;5482.479164479368;5499.890002199973;5866.679997000683;5989.55078369124;6523.5107366947195;6590.80227593173;6631.4031737143405;4452.664200739765;4455.420795296591;4471.82112715491;4493.41585593874;4499.392582001454;6755.932440675629;6959.269276725921;6997.580145191305;7106.822329441754;7172.964135179395;3957.2876882161486;4086.0602061526015;4279;4326.63223625989;4453.109378124388;3841.4621952926614;3990.4014397839974;4631.583157515773;4649.604783593365;4649.907001859944;4584.564466375689;5080.49195080489;5303.469653034743;5352.019752691529;5445.618806683494;5510.779568817283;6198.132261483396;6704.195496540447;6718.697658605374;6737.730490780412;1563.7263478891148;1579.5814109261055;1582.9050256984617;1585.5798213473438;1590.83296253894;883.9071897450804;907.8547432410783;984.9359791613522;1007.5566750629699;1039.9636012739518;6287.339829317947;6314.684265786692;6323.083152942798;6323.494120470351;6353.364663533702;5868.361615798232;6143.56995010354;6986.930130698628;6997.685104170325;7023.754168604074;4922.409310882717;5787.971060144757;6236;6243.875122497525;6261.843453913598;3478.913027174341;3507.473878918183;3611.7652352596997;3651.379265524867;3699.667029967316;6237.688115594202;6246.562740608205;7143.142702940458;7144.64276786169;7160.427165826722;6956.200036995709;7115.644217789089;7214.494985350975;7228.566286022856;7229.313215244543;6739.797806065826;6780.847255966399;7141.571505709674;7150.034745309421;7186;5997.3402925677265;6290.119383286252;6964.512484126265;7074.134975334998;7127.643617818984;5499.752511137089;6303.707739913215;6307.526935479766;6787.355201255873;6911.5853048815225;7158.713651454092;7160.641967901688;7204.855902882018;7225.592388642818;7257.3873405575005;4367.694261401798;4483.327500874895;4519.305260527302;4537.546245375436;4540.318952157203;5931.733072011683;6908.827279318058;6996.2714541327905;7016.333448322402;7030.472714546328;6507.284198738041;6727.856264435156;6731.932680673131;6734.461243100638;6755.966220168868;5277.788888444419;6670.466362690876;6730.192376914802;6764.526483146133;6775.051492791112;6448;6571;6634.336566343327;6645.900311495235;6666.533342665967;5849.327327357409;6636.634985075867;6648.667566621552;6685.498587605852;6727;5527.336719593674;6305.274893387135;6324.525660575383;6430.678466076771;6484.935150648433 12:43:05 INFO - PID 1646 | |5;yui.html;772.8029352515108;857.8027053777627;866.6100254885301;880.2166072195747;887.0818702642764;16696.2486688099;17222.397216097423;17258.309667613295;18351.632967340644;19066.61866762664;18102.5474363141;18456.815431845676;18474.44576662699;18604.813951860477;18672.253109875623;854.9658013679446;1015.8425444056164;1034.6068493972318;1042.3224903812506;1045.414567842007;1005.0502275349797;1014.90865822076;1027.6762819711805;1031.4842578710645;1031.943243121628;1082.7509672775266;1086.6631344283257;1089.5042755546208;1090.3294473898534;1090.918181136414;866.5537248317122;1069.9995504203594;1077.9892201078007;1079.83262594298;1080.2276372393737;779.9415043871733;792;798.5647821937058;800.9439339246255;801.9919800801975;14799.334029968677;16886.493405197863;17336.133193340283;17571.209295581728;17594.736078958827;17467.912660436617;17490.300387984465;17788.822111778845;17822.732659010126;17945.192466339045;14181.716365672628;14304;14313.069650472686;14468.204248766313;14474.203918784462;3588.085038315213;3592.6766591006935;3608.2061946371755;3612.819359032038;3619.49327094207;123246.38376808148;127134;127137;128246.3587682065;130116.69883301141;13481.460741570227;13806.309684515734;13861.514846980308;13898.305084745722;13963.441462341392;25490.49019019609;25585.48829023409;25774.226773196835;25914.35214119662;26127.216183514523;7890.8816367754525;7895.092064412551;7925.643346049442;7938.841223175619;7971.40214483916;3815.9427608585893;3932.311845427039;3942.3692209246956;3945.6448919597374;3946.072672921861;4341.15347507233;4373.343998400265;4457.754823484739;4462.933056004098;4495.168393847149;827.3463963468912;854.6624083486988;889.21304645388;900.4552245891273;909.9226565741853;655.3381085103983;663.7046514301089;671.2414971082645;672.6804767735287;696.1367903799253;11075.778484430266;11772.234804737665;11863.46614402354;11953.880461195451;12158.088143389281;647.4043879630751;660.4914216053708;660.7489154121403;670.8993650952397;690.6305126757161;5425.37608175057;5570.108782594765;5578.804741834017;5659.434056594308;5661.34894487131;107491;108199;110846.44576777064;112999;113912;96432;98140;98274.71270086037;99065;101204;82121;82369.17630823735;82714.3457130866;82822;83275;10604.681859544226;11053.889461105286;11108.055815255793;11123.944380278046;11285.943570282096;40003;41469;41667.7916610415;41991.79004105082;42339;33494.497582536766;51587;52192.69518262074;53063;53410;13794.172349659053;13962.511312104027;14064.156150630995;14244.786328204878;14292.999490035598;15328.770068448759;15506.922465388052;15616.4534241301;15636.609084772972;15684.215789210266;12019.81970270464;14547.127172369692;14588.416463341347;14720.969532132647;14767.92616036913;12649.304288264233;14197.929010355296;14238.501652442115;14525;14585;12033.75932481328;13326.40031198579;13837.100588461717;13987.650308741955;14215.50245741394;10712.732181695208;10731.946340268249;10756.51595678212;10904.509297081491;10945.616903408343;10583.782864970626;11261.76120626714;11576.536938522364;11707.473163707484;12006.21959572625 12:43:05 INFO - PID 1646 | __end_tp_report 12:43:05 INFO - PID 1646 | __start_cc_report 12:43:05 INFO - PID 1646 | _x_x_mozilla_cycle_collect,428 12:43:05 INFO - PID 1646 | __end_cc_report 12:43:05 INFO - PID 1646 | __startTimestamp1492285385244__endTimestamp 12:43:05 INFO - PID 1646 | 12:43:05 INFO - PID 1646 | ------- Summary: start ------- 12:43:05 INFO - PID 1646 | Number of tests: 6 12:43:05 INFO - PID 1646 | 12:43:05 INFO - PID 1646 | [#0] dojo.html Cycles:165 Average:6197.84 Median:6408.07 stddev:757.50 (11.8%) stddev-sans-first:724.90 12:43:05 INFO - PID 1646 | Values: 3299.7 3360.0 3561.8 3565.0 3616.8 5312.9 6108.6 6212.8 6216.7 6286.3 6186.0 6203.3 6204.9 6219.3 6282.7 5484.6 5516.2 5518.4 5522.7 5523.3 5512.6 5527.7 5583.5 5605.9 5671.0 5427.3 5475.0 5526.8 5538.5 5591.4 5492.1 5502.1 5505.3 5513.3 5559.6 5382.9 5443.7 5444.9 5458.3 5479.2 5060.7 6021.5 6164.6 6181.0 6241.9 6715.8 6735.4 6769.0 6773.1 6776.3 5619.2 6855.6 6860.1 6886.9 6907.2 6249.3 6300.7 6303.9 6337.5 6340.6 6888.2 6937.6 6984.5 7001.8 7003.3 6447.0 6567.4 6584.1 6593.3 6600.6 6842.4 6847.6 6864.8 6881.4 6883.9 6489.4 6571.1 6641.5 6681.8 6790.3 6707.8 6784.5 6802.4 6812.5 6831.6 5376.8 5387.4 5406.1 5410.1 5456.6 4736.6 4753.6 4761.7 4763.0 4815.8 6180.4 6326.8 6338.6 6347.2 6363.3 6802.5 6866.3 6882.6 6883.1 6885.8 6238.3 6263.8 6299.0 6321.6 6356.5 5981.3 6033.7 6038.1 6077.9 6082.9 6931.7 6977.8 6986.6 7000.6 7007.1 6557.1 6742.4 6891.4 6894.3 6903.5 6788.8 6812.3 6945.9 6984.3 7020.3 6729.0 6739.1 6754.2 6754.9 6758.2 6516.3 6549.8 6793.9 6864.0 6894.7 5535.3 6544.8 6618.5 6625.3 6630.9 6577.0 6587.9 6604.7 6618.0 6631.5 5216.7 6305.0 6336.3 6451.5 6477.6 6408.1 6535.2 6584.2 6613.0 6634.7 5595.2 6301.4 6454.1 6466.2 6470.9 12:43:05 INFO - PID 1646 | 12:43:05 INFO - PID 1646 | [#1] ext.html Cycles:180 Average:45644.23 Median:176409.18 stddev:80024.55 (45.4%) stddev-sans-first:80178.18 12:43:05 INFO - PID 1646 | Values: 791.2 833.3 1075.6 1283.4 1312.7 38074.8 38386.0 38564.6 38678.0 38692.2 38340.0 38666.0 38717.5 38738.4 38767.6 8291.2 8519.7 8522.0 8529.0 8529.9 8518.2 8639.0 8649.0 8651.6 8675.8 8046.4 8579.7 8655.6 8656.0 8672.9 7276.4 8542.5 8615.1 8630.4 8634.0 8457.7 8479.9 8486.4 8494.7 8510.8 37852.8 37900.4 37979.4 38023.7 38077.2 3650.7 3691.2 3692.2 3695.6 3696.9 24140.4 24321.9 24344.9 24401.9 24464.0 3053.3 3138.9 3139.7 3141.4 3150.0 303035.0 303199.0 303569.0 303578.0 303944.0 39381.0 40135.6 48938.5 49029.8 49258.0 50715.0 50811.7 52017.2 53677.9 53765.9 14415.9 14514.3 14518.0 14534.4 14562.7 11561.5 11669.4 11676.6 11678.9 11699.4 13923.3 13983.9 13985.2 14010.3 14019.0 5034.8 5570.9 6078.8 6102.5 6130.1 975.3 1027.2 1032.4 1038.4 1079.3 23747.2 24717.3 24825.4 24917.4 24945.5 1125.4 1127.1 1162.3 1166.4 1171.4 12626.3 12775.6 12776.5 12784.7 12795.4 315754.0 318350.0 319112.2 319586.0 320798.0 55496.0 55727.7 55856.0 55856.7 56098.4 1489.9 1534.1 1570.3 1575.8 1578.6 20145.5 24187.0 24406.8 24488.8 24700.8 38716.6 38847.6 38861.2 38896.8 38936.0 289033.0 292584.0 292831.0 293816.0 294719.0 35271.6 35394.3 35427.8 36180.5 37172.7 45192.8 45675.8 46065.0 46216.8 46221.0 29535.3 29782.0 30778.5 31522.4 31871.2 30790.5 31215.6 31641.8 31677.8 31723.0 36298.8 36975.0 37042.0 37213.0 37403.8 31164.2 31400.5 31579.8 31738.2 32020.4 19404.9 23158.2 24638.0 24851.9 24877.8 12:43:05 INFO - PID 1646 | 12:43:05 INFO - PID 1646 | [#2] jquery.html Cycles:180 Average:36563.47 Median:2247.61 stddev:97055.93 (4318.2%) stddev-sans-first:97290.18 12:43:05 INFO - PID 1646 | Values: 386.4 430.1 439.6 441.5 466.2 21057.7 21257.6 21610.2 21782.9 22034.2 19365.4 21101.4 21346.9 21717.6 21887.2 6052.5 6223.0 6241.9 6248.1 6264.1 4750.5 6018.8 6078.1 6166.8 6258.5 6109.3 6183.5 6186.7 6201.2 6205.4 6060.3 6172.2 6204.5 6207.0 6209.4 5468.2 5523.6 5551.7 5560.4 5659.9 23228.7 27307.0 27374.2 28131.6 28812.7 2183.9 2256.7 2259.1 2267.4 2269.2 17655.9 17727.8 17734.7 18313.5 18346.6 2184.3 2187.7 2232.1 2233.6 2238.6 159843.0 167380.0 168314.0 168562.0 169200.2 10592.3 12681.5 12925.7 12960.6 13283.4 16518.8 16713.9 16720.0 16752.7 16757.0 8780.2 8916.7 9110.9 9199.0 9222.4 7125.3 8695.8 8745.7 8872.4 8875.6 7809.3 7830.5 7859.3 7964.0 7972.3 2158.5 2162.6 2162.8 2164.7 2314.0 912.6 927.2 961.0 1007.9 1017.3 18821.8 19701.4 20075.4 20556.5 21000.3 983.0 1011.4 1017.8 1018.4 1019.0 9959.5 10651.6 10702.0 10710.9 10746.7 512743.4 577126.0 581896.0 581905.0 592867.0 129086.0 134069.3 138977.0 139068.0 141899.3 1178.8 1213.2 1269.2 1271.5 1271.9 21173.4 21256.1 21318.3 21668.5 21788.8 57493.1 57997.0 58908.0 59791.7 60297.7 54169.4 79485.0 80776.2 82326.0 82825.6 8572.8 8605.3 8691.8 8937.9 8943.8 11274.0 11682.4 13251.1 13612.6 13927.6 9872.5 11697.4 11770.1 11816.6 12004.9 9044.9 9856.3 9979.6 10283.6 10889.4 11088.3 11484.4 11663.7 11698.0 11876.8 8537.8 8718.8 8783.3 8910.2 8954.3 10161.7 10832.1 11261.9 11786.4 11812.0 12:43:05 INFO - PID 1646 | 12:43:05 INFO - PID 1646 | [#3] mootools.html Cycles:180 Average:7202.99 Median:3870.60 stddev:4530.18 (117.0%) stddev-sans-first:4522.24 12:43:05 INFO - PID 1646 | Values: 1446.2 1490.1 1494.4 1510.7 1533.0 11583.5 11990.9 12029.9 12051.7 12351.2 11906.6 12036.3 12066.9 12107.0 12112.8 2532.5 2583.5 2585.7 2586.8 2589.4 2485.9 2540.1 2546.1 2561.6 2565.4 2475.8 2551.9 2569.6 2573.7 2574.3 2485.7 2519.3 2537.2 2539.3 2555.1 2505.8 2533.6 2533.7 2535.1 2540.8 11677.2 11827.4 11887.9 11901.9 11941.2 6591.3 6836.8 6844.0 6845.6 6855.4 3618.0 4129.3 4320.5 4407.0 4412.4 1232.7 1252.3 1301.9 1306.5 1308.4 16350.0 16504.9 16642.1 16755.7 16945.7 5333.6 5337.0 5673.5 5824.4 5896.4 12143.8 12291.8 12344.0 12424.7 12443.6 6680.1 6714.2 6719.9 6726.1 6768.6 6022.5 6141.2 6206.3 6207.3 6284.5 4341.7 4468.7 4503.1 4510.3 4521.7 1234.1 1302.3 1316.8 1330.6 1333.2 756.9 763.5 783.4 793.8 827.1 6646.6 6994.3 7012.3 7038.6 7070.2 760.9 885.2 919.7 952.8 962.7 4123.2 4250.2 4379.8 4438.9 4507.1 14491.9 15069.0 15224.6 15227.0 15239.9 14966.0 15159.1 15165.4 15185.5 15479.2 7635.9 7903.1 8240.1 8347.8 8477.3 5619.8 6715.1 6764.9 7142.5 7230.9 10068.8 10776.8 13490.7 13981.3 14088.4 10595.5 13731.7 13857.5 14039.3 14231.6 9527.2 9625.9 9666.9 9713.8 9773.3 10797.6 10883.1 10911.0 10923.5 10925.3 9040.3 9274.5 9292.5 9422.8 9437.6 9467.9 9508.9 9527.8 9543.9 9683.9 8101.7 8323.4 8408.5 8420.9 8422.2 5561.8 6070.7 6121.4 6223.0 6235.8 5701.2 6143.2 6199.6 6251.7 6331.4 12:43:05 INFO - PID 1646 | 12:43:05 INFO - PID 1646 | [#4] prototype.html Cycles:180 Average:5371.90 Median:6113.40 stddev:1715.07 (28.1%) stddev-sans-first:1677.99 12:43:05 INFO - PID 1646 | Values: 352.4 399.1 401.7 408.6 422.4 4838.8 6228.3 6303.6 6323.9 6347.6 5962.6 6006.0 6118.4 6194.9 6227.3 4380.4 4454.8 4601.3 4605.0 4629.8 4567.1 4578.7 4578.9 4581.7 4589.7 4538.6 4554.9 4561.6 4570.4 4591.7 4377.2 4550.5 4557.4 4557.9 4570.4 3278.2 3611.9 3716.5 3927.3 3939.0 5753.4 5871.9 6102.0 6108.4 6135.5 4373.8 5425.2 5426.3 5482.5 5499.9 5866.7 5989.6 6523.5 6590.8 6631.4 4452.7 4455.4 4471.8 4493.4 4499.4 6755.9 6959.3 6997.6 7106.8 7173.0 3957.3 4086.1 4279.0 4326.6 4453.1 3841.5 3990.4 4631.6 4649.6 4649.9 4584.6 5080.5 5303.5 5352.0 5445.6 5510.8 6198.1 6704.2 6718.7 6737.7 1563.7 1579.6 1582.9 1585.6 1590.8 883.9 907.9 984.9 1007.6 1040.0 6287.3 6314.7 6323.1 6323.5 6353.4 5868.4 6143.6 6986.9 6997.7 7023.8 4922.4 5788.0 6236.0 6243.9 6261.8 3478.9 3507.5 3611.8 3651.4 3699.7 6237.7 6246.6 7143.1 7144.6 7160.4 6956.2 7115.6 7214.5 7228.6 7229.3 6739.8 6780.8 7141.6 7150.0 7186.0 5997.3 6290.1 6964.5 7074.1 7127.6 5499.8 6303.7 6307.5 6787.4 6911.6 7158.7 7160.6 7204.9 7225.6 7257.4 4367.7 4483.3 4519.3 4537.5 4540.3 5931.7 6908.8 6996.3 7016.3 7030.5 6507.3 6727.9 6731.9 6734.5 6756.0 5277.8 6670.5 6730.2 6764.5 6775.1 6448.0 6571.0 6634.3 6645.9 6666.5 5849.3 6636.6 6648.7 6685.5 6727.0 5527.3 6305.3 6324.5 6430.7 6484.9 12:43:05 INFO - PID 1646 | 12:43:05 INFO - PID 1646 | [#5] yui.html Cycles:180 Average:21772.52 Median:17479.11 stddev:31747.84 (181.6%) stddev-sans-first:31797.75 12:43:05 INFO - PID 1646 | Values: 772.8 857.8 866.6 880.2 887.1 16696.2 17222.4 17258.3 18351.6 19066.6 18102.5 18456.8 18474.4 18604.8 18672.3 855.0 1015.8 1034.6 1042.3 1045.4 1005.1 1014.9 1027.7 1031.5 1031.9 1082.8 1086.7 1089.5 1090.3 1090.9 866.6 1070.0 1078.0 1079.8 1080.2 779.9 792.0 798.6 800.9 802.0 14799.3 16886.5 17336.1 17571.2 17594.7 17467.9 17490.3 17788.8 17822.7 17945.2 14181.7 14304.0 14313.1 14468.2 14474.2 3588.1 3592.7 3608.2 3612.8 3619.5 123246.4 127134.0 127137.0 128246.4 130116.7 13481.5 13806.3 13861.5 13898.3 13963.4 25490.5 25585.5 25774.2 25914.4 26127.2 7890.9 7895.1 7925.6 7938.8 7971.4 3815.9 3932.3 3942.4 3945.6 3946.1 4341.2 4373.3 4457.8 4462.9 4495.2 827.3 854.7 889.2 900.5 909.9 655.3 663.7 671.2 672.7 696.1 11075.8 11772.2 11863.5 11953.9 12158.1 647.4 660.5 660.7 670.9 690.6 5425.4 5570.1 5578.8 5659.4 5661.3 107491.0 108199.0 110846.4 112999.0 113912.0 96432.0 98140.0 98274.7 99065.0 101204.0 82121.0 82369.2 82714.3 82822.0 83275.0 10604.7 11053.9 11108.1 11123.9 11285.9 40003.0 41469.0 41667.8 41991.8 42339.0 33494.5 51587.0 52192.7 53063.0 53410.0 13794.2 13962.5 14064.2 14244.8 14293.0 15328.8 15506.9 15616.5 15636.6 15684.2 12019.8 14547.1 14588.4 14721.0 14767.9 12649.3 14197.9 14238.5 14525.0 14585.0 12033.8 13326.4 13837.1 13987.7 14215.5 10712.7 10731.9 10756.5 10904.5 10945.6 10583.8 11261.8 11576.5 11707.5 12006.2 12:43:05 INFO - PID 1646 | -------- Summary: end -------- 12:43:05 INFO - PID 1646 | 12:43:05 INFO - TEST-INFO | 1646: exit 0 12:43:05 INFO - TEST-OK | dromaeo_css | took 1114687ms 12:43:05 INFO - TEST-START | kraken 12:43:05 INFO - operating with platform_type : mac_ 12:43:05 INFO - Initialising browser for kraken test... 12:43:05 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpjr7F87/profile http://localhost:49243/getInfo.html) 12:43:08 INFO - PID 1655 | 2017-04-15 12:43:08.432 plugin-container[1656:10580] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 12:43:08 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:43:08 INFO - PID 1655 | 2017-04-15 12:43:08.436 plugin-container[1656:10580] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 12:43:08 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:43:08 INFO - PID 1655 | 2017-04-15 12:43:08.436 plugin-container[1656:10580] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:43:08 INFO - PID 1655 | __metrics Screen width/height:1600/1200 12:43:08 INFO - PID 1655 | colorDepth:24 12:43:08 INFO - PID 1655 | Browser inner width/height: 1024/689 12:43:08 INFO - PID 1655 | __metrics 12:43:08 ERROR - PID 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 12:43:09 INFO - TEST-INFO | 1655: exit 0 12:43:09 INFO - Browser initialized. 12:43:09 INFO - Running cycle 1/1 for kraken test... 12:43:09 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpjr7F87/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:43:12 INFO - PID 1657 | 2017-04-15 12:43:12.365 plugin-container[1658:10702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x892f, name = 'com.apple.tsm.portname' 12:43:12 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:43:12 INFO - PID 1657 | 2017-04-15 12:43:12.366 plugin-container[1658:10702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 12:43:12 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:43:12 INFO - PID 1657 | 2017-04-15 12:43:12.366 plugin-container[1658:10702] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:43:22 INFO - PID 1657 | RSS: Main: 252416000 12:43:22 INFO - PID 1657 | 12:43:25 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 12:43:26 INFO - PID 1657 | RSS: Main: 260005888 12:43:26 INFO - PID 1657 | 12:43:28 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 12:43:29 INFO - PID 1657 | RSS: Main: 267059200 12:43:29 INFO - PID 1657 | 12:43:32 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 12:43:33 INFO - PID 1657 | RSS: Main: 262344704 12:43:33 INFO - PID 1657 | 12:43:35 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 12:43:35 INFO - PID 1657 | RSS: Main: 264196096 12:43:35 INFO - PID 1657 | 12:43:37 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:43:38 INFO - PID 1657 | RSS: Main: 264859648 12:43:38 INFO - PID 1657 | 12:43:41 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 12:43:42 INFO - PID 1657 | RSS: Main: 270221312 12:43:42 INFO - PID 1657 | 12:43:45 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 12:43:45 INFO - PID 1657 | RSS: Main: 271806464 12:43:45 INFO - PID 1657 | 12:43:49 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 12:43:49 INFO - PID 1657 | RSS: Main: 271519744 12:43:49 INFO - PID 1657 | 12:43:51 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:43:51 INFO - PID 1657 | RSS: Main: 271433728 12:43:51 INFO - PID 1657 | 12:43:53 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:43:54 INFO - PID 1657 | RSS: Main: 271507456 12:43:54 INFO - PID 1657 | 12:43:56 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:43:57 INFO - PID 1657 | RSS: Main: 271097856 12:43:57 INFO - PID 1657 | 12:43:59 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:44:00 INFO - PID 1657 | RSS: Main: 271392768 12:44:00 INFO - PID 1657 | 12:44:04 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:44:04 INFO - PID 1657 | RSS: Main: 268595200 12:44:04 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 12:44:06 INFO - PID 1657 | RSS: Main: 270561280 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | __start_tp_report 12:44:06 INFO - PID 1657 | _x_x_mozilla_page_load 12:44:06 INFO - PID 1657 | _x_x_mozilla_page_load_details 12:44:06 INFO - PID 1657 | |i|pagename|runs| 12:44:06 INFO - PID 1657 | |0;ai-astar;109;115;165;149;127;104;166;169;138;115 12:44:06 INFO - PID 1657 | |1;audio-beat-detection;142;140;145;188;113;110;115;115;146;118 12:44:06 INFO - PID 1657 | |2;audio-dft;204;205;259;296;275;214;194;201;195;196 12:44:06 INFO - PID 1657 | |3;audio-fft;80;58;57;83;61;61;65;88;79;60 12:44:06 INFO - PID 1657 | |4;audio-oscillator;87;70;84;70;70;70;90;93;104;108 12:44:06 INFO - PID 1657 | |5;imaging-gaussian-blur;89;109;136;123;89;88;88;89;89;88 12:44:06 INFO - PID 1657 | |6;imaging-darkroom;116;153;97;97;96;100;96;96;97;96 12:44:06 INFO - PID 1657 | |7;imaging-desaturate;91;106;147;96;144;143;90;90;92;90 12:44:06 INFO - PID 1657 | |8;json-parse-financial;53;52;63;58;52;52;52;52;53;53 12:44:06 INFO - PID 1657 | |9;json-stringify-tinderbox;60;57;59;67;64;62;57;56;57;61 12:44:06 INFO - PID 1657 | |10;stanford-crypto-aes;82;85;84;83;85;82;82;85;86;85 12:44:06 INFO - PID 1657 | |11;stanford-crypto-ccm;146;151;147;138;141;138;131;156;207;157 12:44:06 INFO - PID 1657 | |12;stanford-crypto-pbkdf2;187;219;193;237;231;187;189;191;225;235 12:44:06 INFO - PID 1657 | |13;stanford-crypto-sha256-iterative;72;72;69;72;71;69;66;84;94;102 12:44:06 INFO - PID 1657 | __end_tp_report 12:44:06 INFO - PID 1657 | __start_cc_report 12:44:06 INFO - PID 1657 | _x_x_mozilla_cycle_collect,1001 12:44:06 INFO - PID 1657 | __end_cc_report 12:44:06 INFO - PID 1657 | __startTimestamp1492285446800__endTimestamp 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | ------- Summary: start ------- 12:44:06 INFO - PID 1657 | Number of tests: 14 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | [#0] ai-astar Cycles:10 Average:135.70 Median:143.50 stddev:25.21 (17.6%) stddev-sans-first:24.81 12:44:06 INFO - PID 1657 | Values: 109.0 115.0 165.0 149.0 127.0 104.0 166.0 169.0 138.0 115.0 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | [#1] audio-beat-detection Cycles:10 Average:133.20 Median:141.00 stddev:24.20 (17.2%) stddev-sans-first:25.45 12:44:06 INFO - PID 1657 | Values: 142.0 140.0 145.0 188.0 113.0 110.0 115.0 115.0 146.0 118.0 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | [#2] audio-dft Cycles:10 Average:223.90 Median:209.50 stddev:37.90 (18.1%) stddev-sans-first:39.50 12:44:06 INFO - PID 1657 | Values: 204.0 205.0 259.0 296.0 275.0 214.0 194.0 201.0 195.0 196.0 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | [#3] audio-fft Cycles:10 Average:69.20 Median:72.00 stddev:11.87 (16.5%) stddev-sans-first:11.93 12:44:06 INFO - PID 1657 | Values: 80.0 58.0 57.0 83.0 61.0 61.0 65.0 88.0 79.0 60.0 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | [#4] audio-oscillator Cycles:10 Average:84.60 Median:77.00 stddev:14.46 (18.8%) stddev-sans-first:15.31 12:44:06 INFO - PID 1657 | Values: 87.0 70.0 84.0 70.0 70.0 70.0 90.0 93.0 104.0 108.0 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:98.80 Median:88.50 stddev:17.66 (20.0%) stddev-sans-first:18.37 12:44:06 INFO - PID 1657 | Values: 89.0 109.0 136.0 123.0 89.0 88.0 88.0 89.0 89.0 88.0 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | [#6] imaging-darkroom Cycles:10 Average:104.40 Median:96.00 stddev:18.14 (18.9%) stddev-sans-first:18.75 12:44:06 INFO - PID 1657 | Values: 116.0 153.0 97.0 97.0 96.0 100.0 96.0 96.0 97.0 96.0 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | [#7] imaging-desaturate Cycles:10 Average:108.90 Median:90.00 stddev:25.16 (28.0%) stddev-sans-first:25.84 12:44:06 INFO - PID 1657 | Values: 91.0 106.0 147.0 96.0 144.0 143.0 90.0 90.0 92.0 90.0 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:53.00 stddev:3.65 (6.9%) stddev-sans-first:3.86 12:44:06 INFO - PID 1657 | Values: 53.0 52.0 63.0 58.0 52.0 52.0 52.0 52.0 53.0 53.0 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.50 stddev:3.56 (5.9%) stddev-sans-first:3.77 12:44:06 INFO - PID 1657 | Values: 60.0 57.0 59.0 67.0 64.0 62.0 57.0 56.0 57.0 61.0 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:85.00 stddev:1.52 (1.8%) stddev-sans-first:1.45 12:44:06 INFO - PID 1657 | Values: 82.0 85.0 84.0 83.0 85.0 82.0 82.0 85.0 86.0 85.0 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:151.20 Median:149.00 stddev:21.28 (14.3%) stddev-sans-first:22.49 12:44:06 INFO - PID 1657 | Values: 146.0 151.0 147.0 138.0 141.0 138.0 131.0 156.0 207.0 157.0 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:209.40 Median:222.00 stddev:21.72 (9.8%) stddev-sans-first:21.47 12:44:06 INFO - PID 1657 | Values: 187.0 219.0 193.0 237.0 231.0 187.0 189.0 191.0 225.0 235.0 12:44:06 INFO - PID 1657 | 12:44:06 INFO - PID 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.10 Median:72.00 stddev:12.12 (16.8%) stddev-sans-first:12.72 12:44:06 INFO - PID 1657 | Values: 72.0 72.0 69.0 72.0 71.0 69.0 66.0 84.0 94.0 102.0 12:44:06 INFO - PID 1657 | -------- Summary: end -------- 12:44:06 INFO - PID 1657 | 12:44:07 INFO - TEST-INFO | 1657: exit 0 12:44:07 INFO - TEST-OK | kraken | took 62069ms 12:44:07 INFO - SUITE-END | took 1176s 12:44:08 INFO - Completed test suite (00:19:37) 12:44:08 INFO - javascript benchmark 12:44:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7920.855215721907, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3299.6865297796703, 3360.0, 3561.839717212725, 3564.982175089124, 3616.7649102808314, 5312.89374212516, 6108.584917381041, 6212.751489940409, 6216.720247588858, 6286.3399343069, 6186.0, 6203.255609326887, 6204.875902481947, 6219.315875253718, 6282.717277722502, 5484.588655850807, 5516.221700710981, 5518.420565840569, 5522.723863806814, 5523.2543606613, 5512.614117011811, 5527.723613819313, 5583.469570390806, 5605.915911261334, 5671.035923892948, 5427.348718153808, 5474.972625136888, 5526.778928842882, 5538.47384498472, 5591.41290164533, 5492.066348720727, 5502.0921547944545, 5505.3393592768725, 5513.283273174502, 5559.555235581144, 5382.8923421531745, 5443.700596467193, 5444.891102177974, 5458.317710286215, 5479.232907392969, 5060.74696265189, 6021.548383871227, 6164.568480206387, 6181.041938499539, 6241.87516249677, 6715.764948226837, 6735.360140786617, 6769.018492318586, 6773.085633439521, 6776.2546119926765, 5619.213310136584, 6855.622940738257, 6860.0738900248325, 6886.931130688729, 6907.205671347809, 6249.281332646804, 6300.747970081191, 6303.936960630427, 6337.524685648595, 6340.556161068773, 6888.242293347727, 6937.618430986344, 6984.546004509691, 7001.754938577125, 7003.264657211022, 6446.967765161145, 6567.4417674497245, 6584.078229047937, 6593.274739778621, 6600.60396376219, 6842.384185423236, 6847.623380714009, 6864.7940561782225, 6881.449484041264, 6883.8967415488805, 6489.415952564292, 6571.145751052428, 6641.542629227217, 6681.832954176184, 6790.287019862942, 6707.798766037026, 6784.491163162783, 6802.3537763912345, 6812.4550035997, 6831.59010459374, 5376.838694839083, 5387.434319396407, 5406.053940560386, 5410.1343784995, 5456.563474921997, 4736.64475164364, 4753.572178503951, 4761.714297142183, 4763.04739052191, 4815.831445899376, 6180.443760061616, 6326.810195694136, 6338.556301058881, 6347.174867267226, 6363.33185015576, 6802.523823332418, 6866.279040700754, 6882.5870447773295, 6883.070785444002, 6885.758998435031, 6238.251409830849, 6263.812085637347, 6298.968505157445, 6321.620702757849, 6356.45970092538, 5981.252343457068, 6033.668148251888, 6038.064100064497, 6077.908831367533, 6082.878342433127, 6931.722731090801, 6977.79066628002, 6986.615736134561, 7000.649967501707, 7007.089078419773, 6557.1147895035465, 6742.422358704109, 6891.414229790524, 6894.276101009322, 6903.5167538271835, 6788.76239331621, 6812.25065242813, 6945.930540694731, 6984.301569843178, 7020.333068358498, 6729.024291477806, 6739.123913891162, 6754.223264324469, 6754.932450675627, 6758.155230596176, 6516.283208847118, 6549.836254093686, 6793.864122717617, 6864.0, 6894.689738961659, 5535.252740880091, 6544.8363790905605, 6618.470522358296, 6625.337466253336, 6630.900536492053, 6576.967115164393, 6587.934120658732, 6604.735810567716, 6617.966910165418, 6631.502637302126, 5216.713080780593, 6305.0, 6336.271328797247, 6451.451626611791, 6477.578957367756, 6408.07082972957, 6535.150430444012, 6584.176977877765, 6613.008048792527, 6634.701438435185, 5595.216669666332, 6301.369863013662, 6454.128692626436, 6466.159399278064, 6470.870582588228], "value": 6146.261994842135, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.1771757372333, 833.3333333333331, 1075.634284343323, 1283.4352884730718, 1312.7046414556726, 38074.809625951864, 38386.040348991286, 38564.61435385645, 38678.0, 38692.22615547691, 38340.04149896254, 38666.0, 38717.45130194789, 38738.41892371617, 38767.612323876754, 8291.212334828211, 8519.659213631447, 8522.0, 8528.957355213215, 8529.872051919227, 8518.190771876662, 8639.049704532496, 8649.0, 8651.567421628924, 8675.826483470359, 8046.396520260974, 8579.656813727443, 8655.567221638925, 8656.0, 8672.8699069514, 7276.417886569088, 8542.530160841152, 8615.095414981432, 8630.352723545759, 8633.95683021581, 8457.703980360659, 8479.915200848036, 8486.448380855225, 8494.70268540598, 8510.829783404297, 37852.81073594642, 37900.43149352762, 37979.430308545394, 38023.66917157914, 38077.23845523074, 3650.7444478886487, 3691.2063906260014, 3692.1877187018813, 3695.611960744137, 3696.9075773105887, 24140.396490087714, 24321.905514251903, 24344.904479298475, 24401.87799061011, 24464.0, 3053.3282677811067, 3138.8901388451523, 3139.748820094387, 3141.4345417824784, 3149.9762577162287, 303035.0, 303199.0, 303569.0, 303578.0, 303944.0, 39381.0, 40135.59864401319, 48938.510614894105, 49029.75485122623, 49258.01483970372, 50715.0, 50811.745941270055, 52017.219741703906, 53677.92644147173, 53765.92468150615, 14415.92792036054, 14514.274286285643, 14518.0, 14534.418623254951, 14562.708745825024, 11561.537538498365, 11669.35818529989, 11676.591319303783, 11678.941605291919, 11699.356535390465, 13923.30383480822, 13983.86016139846, 13985.230812305217, 14010.299485025707, 14019.0, 5034.773435195425, 5570.916436253459, 6078.817635470943, 6102.542309326729, 6130.080487926757, 975.2831668723483, 1027.2347101409498, 1032.385730490357, 1038.4288641247283, 1079.2553138334524, 23747.168849090198, 24717.25848224556, 24825.379365515644, 24917.377065573506, 24945.501089978097, 1125.3810404277615, 1127.115214556569, 1162.314234601589, 1166.445938179375, 1171.4377098992532, 12626.305553194663, 12775.61673149807, 12776.488940442465, 12784.74430511403, 12795.424205910756, 315754.0, 318350.0, 319112.2133168004, 319586.0, 320798.0, 55496.0, 55727.721361392934, 55856.0, 55856.72071639697, 56098.439015610136, 1489.9255037248095, 1534.0565552185326, 1570.2776722707683, 1575.7715131305897, 1578.5974576483154, 20145.496362591053, 24187.0, 24406.755932440446, 24488.75511244865, 24700.7649617522, 38716.6128338713, 38847.61152388553, 38861.22277554376, 38896.80551597224, 38936.0, 289033.0, 292584.0, 292831.0, 293816.0, 294719.0, 35271.647283526836, 35394.29211415809, 35427.82286088553, 36180.4572931401, 37172.69895553643, 45192.77403612961, 45675.77162114301, 46065.0, 46216.84457888579, 46221.0, 29535.26161845885, 29781.957631482794, 30778.538321925636, 31522.369552609278, 31871.203219919687, 30790.53814192744, 31215.59529821118, 31641.8417907909, 31677.84161079272, 31723.0, 36298.8185059073, 36975.0, 37042.0, 37213.0, 37403.81298093492, 31164.220894477818, 31400.52899206468, 31579.84210078935, 31738.20654483564, 32020.35959280848, 19404.90297548503, 23158.1894633687, 24638.01447942062, 24851.87574062118, 24877.751222487543], "value": 17714.150800667132, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.35671606774724, 430.1075268817205, 439.59776804224157, 441.4768499328296, 466.2074473395229, 21057.68413473797, 21257.618254813464, 21610.24364147253, 21782.89108554455, 22034.228801991914, 19365.419037428863, 21101.417393695498, 21346.89326553365, 21717.565648687058, 21887.233946811866, 6052.546059045567, 6222.9688851555675, 6241.875162496747, 6248.094026366174, 6264.060390941372, 4750.501197374278, 6018.8194354169445, 6078.088286757, 6166.7841625543115, 6258.499320054385, 6109.327973922865, 6183.536234782387, 6186.690665466731, 6201.162843016203, 6205.410486003822, 6060.272767267934, 6172.197614310134, 6204.534659900481, 6207.037909124116, 6209.3790620938, 5468.207109969072, 5523.613347065707, 5551.694656793875, 5560.388357280696, 5659.915101273484, 23228.65157022646, 27307.0, 27374.17877463679, 28131.578026329622, 28812.711872881424, 2183.9235626753148, 2256.650219216024, 2259.0624890670515, 2267.4331417145713, 2269.1830940861278, 17655.852369595934, 17727.847689900245, 17734.733978990327, 18313.542161446072, 18346.633067338713, 2184.2682701295084, 2187.671849222613, 2232.0625337358188, 2233.642617181243, 2238.5746708125403, 159843.0, 167380.0, 168314.0, 168562.0, 169200.15399923045, 10592.311499752492, 12681.49274029047, 12925.676858078623, 12960.611181664377, 13283.409735101755, 16518.75221871673, 16713.916430418012, 16720.0, 16752.664946701, 16757.0, 8780.209781119602, 8916.73249802494, 9110.908890911007, 9199.03410141939, 9222.40054396462, 7125.25184855593, 8695.826083478294, 8745.737627871174, 8872.423292485968, 8875.600597973107, 7809.258120478546, 7830.451868369273, 7859.292663660298, 7964.0, 7972.322352599978, 2158.48196432855, 2162.5566758814407, 2162.8269738421204, 2164.718586583734, 2314.0396735354975, 912.5893347993297, 927.1655510041018, 960.9711708648751, 1007.9344842585208, 1017.3438132404558, 18821.81178188228, 19701.408957731004, 20075.39773806788, 20556.45826563014, 21000.264990725558, 982.9803403931882, 1011.3628414099071, 1017.8269694152012, 1018.3889666200369, 1018.9962886556735, 9959.452230127268, 10651.573937042587, 10702.022979621577, 10710.89289107099, 10746.677599672023, 512743.4362828162, 577126.0, 581896.0, 581905.0, 592867.0, 129086.0, 134069.3296533511, 138977.0, 139068.0, 141899.2905035489, 1178.8113901775675, 1213.1568559850818, 1269.2194300505123, 1271.5168236070233, 1271.9364031798373, 21173.36479905544, 21256.149754009668, 21318.253861114787, 21668.45828854291, 21788.801615911278, 57493.13760293515, 57997.0, 58908.0, 59791.70104149451, 60297.69851150716, 54169.3749187525, 79485.01287467628, 80776.19223807687, 82326.0, 82825.58587207025, 8572.785680358042, 8605.311575074104, 8691.826163476822, 8937.910620893968, 8943.821123577622, 11274.0, 11682.415879206175, 13251.072424930162, 13612.591622251348, 13927.58217253484, 9872.45701486425, 11697.356645384585, 11770.058395328524, 11816.645500634995, 12004.879951200377, 9044.864327034968, 9856.31005829585, 9979.60081596728, 10283.640072597424, 10889.401082940514, 11088.279261847954, 11484.425778711198, 11663.708407289885, 11698.0, 11876.821847672465, 8537.786555335917, 8718.782030449036, 8783.341249406192, 8910.198082172636, 8954.328425368254, 10161.695149145537, 10832.133429325477, 11261.887381126084, 11786.410679466164, 11812.0], "value": 10436.434492327391, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.233496246989, 1490.0761527852733, 1494.3648949196588, 1510.667653116314, 1532.969340613188, 11583.53665853366, 11990.880091199086, 12029.939850300736, 12051.698707532316, 12351.197172183802, 11906.64280071597, 12036.277823330607, 12066.913977742002, 12107.0, 12112.757744845097, 2532.5314816758914, 2583.5220484210386, 2585.650937123483, 2586.8318559293584, 2589.4173810892585, 2485.875706214691, 2540.0855691950883, 2546.121588052119, 2561.602951542514, 2565.3586603349163, 2475.789557887582, 2551.9489610207784, 2569.5760199567144, 2573.6782902137234, 2574.2663340947834, 2485.6768620079365, 2519.3323769201174, 2537.175417989161, 2539.3270783242456, 2555.131255373182, 2505.7744802967823, 2533.6199570064455, 2533.6832895888015, 2535.125381743314, 2540.847549147057, 11677.240979336317, 11827.408629568487, 11887.94056029723, 11901.88098119025, 11941.164118511708, 6591.274959754423, 6836.829079273009, 6843.965780171067, 6845.589264644087, 6855.4172895303955, 3618.0, 4129.2773764591075, 4320.546342634041, 4407.0, 4412.382266482695, 1232.7226374065854, 1252.278117588316, 1301.8958483321312, 1306.45129045801, 1308.4439113376777, 16350.0, 16504.917475412665, 16642.084685342303, 16755.66488670232, 16945.745813812802, 5333.599980001515, 5336.9859726652585, 5673.489385955201, 5824.359320474744, 5896.439838215363, 12143.757124857453, 12291.75416491683, 12344.0, 12424.689382765504, 12443.564475243324, 6680.0647909291765, 6714.227863795629, 6719.932800672029, 6726.058351830748, 6768.56004359715, 6022.519507248383, 6141.201643786369, 6206.317305096436, 6207.255129384503, 6284.528660350401, 4341.717788343748, 4468.664850136252, 4503.099380123988, 4510.255807791743, 4521.728696278234, 1234.1484375780683, 1302.2512055567897, 1316.822228999092, 1330.60081975407, 1333.200079952021, 756.916739158692, 763.4655740981335, 783.4085265624417, 793.7817100297349, 827.0984626756864, 6646.60120392778, 6994.300569942965, 7012.298770122947, 7038.577685338896, 7070.222275549685, 760.9467337286447, 885.1900511032462, 919.7424721078108, 952.7618095476131, 962.6630679262203, 4123.195976784558, 4250.234957707642, 4379.781010949439, 4438.866833994985, 4507.053518761097, 14491.92754036223, 15069.0, 15224.619384515476, 15227.0, 15239.923800380928, 14966.0, 15159.09045457276, 15165.393384264506, 15185.544433667008, 15479.22603869802, 7635.923640763522, 7903.091144518339, 8240.104775901576, 8347.833043339098, 8477.279431248413, 5619.80330688424, 6715.127033485615, 6764.864702706017, 7142.5000249982, 7230.89153662706, 10068.798624027331, 10776.838347424638, 13490.662733431744, 13981.300934953008, 14088.36602352917, 10595.523201455799, 13731.725365492435, 13857.514986975408, 14039.298035098409, 14231.573052808433, 9527.237820974444, 9625.855612165964, 9666.903330966881, 9713.805723885624, 9773.267004974514, 10797.56809727602, 10883.129349652167, 10911.0, 10923.453827308762, 10925.289856159325, 9040.276777857887, 9274.489903055397, 9292.488913109573, 9422.764430889283, 9437.575309111244, 9467.905320946702, 9508.85736713936, 9527.761805954906, 9543.9045609543, 9683.854742178732, 8101.716439924574, 8323.375746818892, 8408.53753043564, 8420.87368689458, 8422.199891010345, 5561.777528898961, 6070.666113363807, 6121.418465245794, 6222.968885155546, 6235.781747638811, 5701.201831743476, 6143.170671959407, 6199.605088855152, 6251.6874156292915, 6331.398517140864], "value": 5461.380386691495, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.43786161073086, 399.1239229890388, 401.67865707434044, 408.6322309921072, 422.3601244115165, 4838.758062096898, 6228.318354044417, 6303.55875088744, 6323.936760632392, 6347.5556711030185, 5962.552808539355, 6005.987994022008, 6118.418750218733, 6194.938050619492, 6227.28386235583, 4380.430544029272, 4454.754988475633, 4601.309803529472, 4605.0, 4629.754569034016, 4567.13224487348, 4578.679492435531, 4578.862634120982, 4581.656375771814, 4589.691937797728, 4538.614217791492, 4554.908901821962, 4561.635069194473, 4570.382998295221, 4591.678582499209, 4377.22296355456, 4550.476695180047, 4557.384753058328, 4557.908841823178, 4570.428696412949, 3278.1804548862783, 3611.873584424559, 3716.479692843004, 3927.3323534999117, 3938.9803050984847, 5753.367129615739, 5871.854988277288, 6102.0, 6108.388166933981, 6135.478484328837, 4373.825046998117, 5425.240466334716, 5426.348838139409, 5482.479164479368, 5499.890002199973, 5866.679997000683, 5989.55078369124, 6523.5107366947195, 6590.80227593173, 6631.4031737143405, 4452.664200739765, 4455.420795296591, 4471.82112715491, 4493.41585593874, 4499.392582001454, 6755.932440675629, 6959.269276725921, 6997.580145191305, 7106.822329441754, 7172.964135179395, 3957.2876882161486, 4086.0602061526015, 4279.0, 4326.63223625989, 4453.109378124388, 3841.4621952926614, 3990.4014397839974, 4631.583157515773, 4649.604783593365, 4649.907001859944, 4584.564466375689, 5080.49195080489, 5303.469653034743, 5352.019752691529, 5445.618806683494, 5510.779568817283, 6198.132261483396, 6704.195496540447, 6718.697658605374, 6737.730490780412, 1563.7263478891148, 1579.5814109261055, 1582.9050256984617, 1585.5798213473438, 1590.83296253894, 883.9071897450804, 907.8547432410783, 984.9359791613522, 1007.5566750629699, 1039.9636012739518, 6287.339829317947, 6314.684265786692, 6323.083152942798, 6323.494120470351, 6353.364663533702, 5868.361615798232, 6143.56995010354, 6986.930130698628, 6997.685104170325, 7023.754168604074, 4922.409310882717, 5787.971060144757, 6236.0, 6243.875122497525, 6261.843453913598, 3478.913027174341, 3507.473878918183, 3611.7652352596997, 3651.379265524867, 3699.667029967316, 6237.688115594202, 6246.562740608205, 7143.142702940458, 7144.64276786169, 7160.427165826722, 6956.200036995709, 7115.644217789089, 7214.494985350975, 7228.566286022856, 7229.313215244543, 6739.797806065826, 6780.847255966399, 7141.571505709674, 7150.034745309421, 7186.0, 5997.3402925677265, 6290.119383286252, 6964.512484126265, 7074.134975334998, 7127.643617818984, 5499.752511137089, 6303.707739913215, 6307.526935479766, 6787.355201255873, 6911.5853048815225, 7158.713651454092, 7160.641967901688, 7204.855902882018, 7225.592388642818, 7257.3873405575005, 4367.694261401798, 4483.327500874895, 4519.305260527302, 4537.546245375436, 4540.318952157203, 5931.733072011683, 6908.827279318058, 6996.2714541327905, 7016.333448322402, 7030.472714546328, 6507.284198738041, 6727.856264435156, 6731.932680673131, 6734.461243100638, 6755.966220168868, 5277.788888444419, 6670.466362690876, 6730.192376914802, 6764.526483146133, 6775.051492791112, 6448.0, 6571.0, 6634.336566343327, 6645.900311495235, 6666.533342665967, 5849.327327357409, 6636.634985075867, 6648.667566621552, 6685.498587605852, 6727.0, 5527.336719593674, 6305.274893387135, 6324.525660575383, 6430.678466076771, 6484.935150648433], "value": 4831.900899249503, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.8029352515108, 857.8027053777627, 866.6100254885301, 880.2166072195747, 887.0818702642764, 16696.2486688099, 17222.397216097423, 17258.309667613295, 18351.632967340644, 19066.61866762664, 18102.5474363141, 18456.815431845676, 18474.44576662699, 18604.813951860477, 18672.253109875623, 854.9658013679446, 1015.8425444056164, 1034.6068493972318, 1042.3224903812506, 1045.414567842007, 1005.0502275349797, 1014.90865822076, 1027.6762819711805, 1031.4842578710645, 1031.943243121628, 1082.7509672775266, 1086.6631344283257, 1089.5042755546208, 1090.3294473898534, 1090.918181136414, 866.5537248317122, 1069.9995504203594, 1077.9892201078007, 1079.83262594298, 1080.2276372393737, 779.9415043871733, 792.0, 798.5647821937058, 800.9439339246255, 801.9919800801975, 14799.334029968677, 16886.493405197863, 17336.133193340283, 17571.209295581728, 17594.736078958827, 17467.912660436617, 17490.300387984465, 17788.822111778845, 17822.732659010126, 17945.192466339045, 14181.716365672628, 14304.0, 14313.069650472686, 14468.204248766313, 14474.203918784462, 3588.085038315213, 3592.6766591006935, 3608.2061946371755, 3612.819359032038, 3619.49327094207, 123246.38376808148, 127134.0, 127137.0, 128246.3587682065, 130116.69883301141, 13481.460741570227, 13806.309684515734, 13861.514846980308, 13898.305084745722, 13963.441462341392, 25490.49019019609, 25585.48829023409, 25774.226773196835, 25914.35214119662, 26127.216183514523, 7890.8816367754525, 7895.092064412551, 7925.643346049442, 7938.841223175619, 7971.40214483916, 3815.9427608585893, 3932.311845427039, 3942.3692209246956, 3945.6448919597374, 3946.072672921861, 4341.15347507233, 4373.343998400265, 4457.754823484739, 4462.933056004098, 4495.168393847149, 827.3463963468912, 854.6624083486988, 889.21304645388, 900.4552245891273, 909.9226565741853, 655.3381085103983, 663.7046514301089, 671.2414971082645, 672.6804767735287, 696.1367903799253, 11075.778484430266, 11772.234804737665, 11863.46614402354, 11953.880461195451, 12158.088143389281, 647.4043879630751, 660.4914216053708, 660.7489154121403, 670.8993650952397, 690.6305126757161, 5425.37608175057, 5570.108782594765, 5578.804741834017, 5659.434056594308, 5661.34894487131, 107491.0, 108199.0, 110846.44576777064, 112999.0, 113912.0, 96432.0, 98140.0, 98274.71270086037, 99065.0, 101204.0, 82121.0, 82369.17630823735, 82714.3457130866, 82822.0, 83275.0, 10604.681859544226, 11053.889461105286, 11108.055815255793, 11123.944380278046, 11285.943570282096, 40003.0, 41469.0, 41667.7916610415, 41991.79004105082, 42339.0, 33494.497582536766, 51587.0, 52192.69518262074, 53063.0, 53410.0, 13794.172349659053, 13962.511312104027, 14064.156150630995, 14244.786328204878, 14292.999490035598, 15328.770068448759, 15506.922465388052, 15616.4534241301, 15636.609084772972, 15684.215789210266, 12019.81970270464, 14547.127172369692, 14588.416463341347, 14720.969532132647, 14767.92616036913, 12649.304288264233, 14197.929010355296, 14238.501652442115, 14525.0, 14585.0, 12033.75932481328, 13326.40031198579, 13837.100588461717, 13987.650308741955, 14215.50245741394, 10712.732181695208, 10731.946340268249, 10756.51595678212, 10904.509297081491, 10945.616903408343, 10583.782864970626, 11261.76120626714, 11576.536938522364, 11707.473163707484, 12006.21959572625], "value": 8235.661818609688, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1594.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 115.0, 165.0, 149.0, 127.0, 104.0, 166.0, 169.0, 138.0, 115.0], "value": 135.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 140.0, 145.0, 188.0, 113.0, 110.0, 115.0, 115.0, 146.0, 118.0], "value": 133.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 205.0, 259.0, 296.0, 275.0, 214.0, 194.0, 201.0, 195.0, 196.0], "value": 223.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 57.0, 83.0, 61.0, 61.0, 65.0, 88.0, 79.0, 60.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 84.0, 70.0, 70.0, 70.0, 90.0, 93.0, 104.0, 108.0], "value": 84.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 109.0, 136.0, 123.0, 89.0, 88.0, 88.0, 89.0, 89.0, 88.0], "value": 98.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 153.0, 97.0, 97.0, 96.0, 100.0, 96.0, 96.0, 97.0, 96.0], "value": 104.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 106.0, 147.0, 96.0, 144.0, 143.0, 90.0, 90.0, 92.0, 90.0], "value": 108.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 63.0, 58.0, 52.0, 52.0, 52.0, 52.0, 53.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 57.0, 59.0, 67.0, 64.0, 62.0, 57.0, 56.0, 57.0, 61.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 84.0, 83.0, 85.0, 82.0, 82.0, 85.0, 86.0, 85.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 151.0, 147.0, 138.0, 141.0, 138.0, 131.0, 156.0, 207.0, 157.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 219.0, 193.0, 237.0, 231.0, 187.0, 189.0, 191.0, 225.0, 235.0], "value": 209.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 69.0, 72.0, 71.0, 69.0, 66.0, 84.0, 94.0, 102.0], "value": 77.1, "unit": "score"}]}]} 12:44:08 INFO - Return code: 0 12:44:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:44:08 ERROR - # TBPL SUCCESS # 12:44:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:44:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:44:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:44:08 INFO - 2017-04-15 12:44:08.597 screenresolution[1663:11100] starting screenresolution argv=screenresolution get 12:44:08 INFO - 2017-04-15 12:44:08.599 screenresolution[1663:11100] Display 0: 1600x1200x32@60 12:44:08 INFO - 2017-04-15 12:44:08.605 screenresolution[1664:11103] starting screenresolution argv=screenresolution list 12:44:08 INFO - Available Modes on Display 0 12:44:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:44:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:44:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:44:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:44:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:44:08 INFO - Intel Iris: 12:44:08 INFO - Chipset Model: Intel Iris 12:44:08 INFO - Type: GPU 12:44:08 INFO - Bus: Built-In 12:44:08 INFO - VRAM (Dynamic, Max): 1536 MB 12:44:08 INFO - Vendor: Intel (0x8086) 12:44:08 INFO - Device ID: 0x0a2e 12:44:08 INFO - Revision ID: 0x0009 12:44:08 INFO - Displays: 12:44:08 INFO - Display: 12:44:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:44:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:44:08 INFO - Display Serial Number: 1600x1200 60 12:44:08 INFO - Main Display: Yes 12:44:08 INFO - Mirror: Off 12:44:08 INFO - Online: Yes 12:44:08 INFO - Rotation: Supported 12:44:08 INFO - Return code: 0 12:44:08 INFO - Running post-action listener: _resource_record_post_action 12:44:08 INFO - [mozharness: 2017-04-15 19:44:08.841411Z] Finished run-tests step (success) 12:44:08 INFO - Running post-run listener: _resource_record_post_run 12:44:08 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 33498112; Write bytes: 519602688; Read time: 201; Write time: 1632 12:44:08 INFO - TinderboxPrint: CPU usage
54.0% 12:44:08 INFO - TinderboxPrint: I/O read bytes / time
33,498,112 / 201 12:44:08 INFO - TinderboxPrint: I/O write bytes / time
519,602,688 / 1,632 12:44:08 INFO - TinderboxPrint: CPU idle
2,203.3 (46.0%) 12:44:08 INFO - TinderboxPrint: CPU system
960.2 (20.0%) 12:44:08 INFO - TinderboxPrint: CPU user
1,630.1 (34.0%) 12:44:08 INFO - TinderboxPrint: Swap in / out
238,419,968 / 0 12:44:08 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205816320; Write bytes: 206205952; Read time: 16558; Write time: 441 12:44:08 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31458304; Write bytes: 306527744; Read time: 182; Write time: 1164 12:44:09 INFO - Running post-run listener: _upload_blobber_files 12:44:09 INFO - Blob upload gear active. 12:44:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:44:09 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:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:44:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:44:09 INFO - (blobuploader) - INFO - Open directory for files ... 12:44:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:44:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:44:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:44:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:44:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:44:10 INFO - (blobuploader) - INFO - Done attempting. 12:44:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:44:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:44:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:44:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:44:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:44:11 INFO - (blobuploader) - INFO - Done attempting. 12:44:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:44:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:44:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:44:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:44:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:44:12 INFO - (blobuploader) - INFO - Done attempting. 12:44:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:44:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:44:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:44:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:44:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:44:14 INFO - (blobuploader) - INFO - Done attempting. 12:44:14 INFO - (blobuploader) - INFO - Iteration through files over. 12:44:14 INFO - Return code: 0 12:44:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:44:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:44:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7de93507e45b6655000de9dd0e5ff0c3da9c82038958c26d6d913add08b143093ce6d61a09b0c530492d5812781d1226efbf2d814d501e6597cb6001aa225cc0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cf43f22cc8ef368155e4c9233aa6a95002705356e5b91ff2ca01316f023e14a25e11226309b33d4d48e437bacde69beef9cef90b402f0da437f4fbdb0825f25", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b65ea6e99a4fb76274b5cc0c14f67a1e321a21fda05a7856a594e8ee55b659d87f92116ef0d85485748d26f665b9d7117dfb3cd5c042ed682806eaffa3d623e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feb39c5b9e4deb8617484816dcc5f2df3b313a3754b24347667e27c48d15534a69654a7dc5c957303de9fae36faef7b166dc1e48218c909c13a8ab838b8f3bc3"} 12:44:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:44:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:44:14 INFO - Contents: 12:44:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7de93507e45b6655000de9dd0e5ff0c3da9c82038958c26d6d913add08b143093ce6d61a09b0c530492d5812781d1226efbf2d814d501e6597cb6001aa225cc0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cf43f22cc8ef368155e4c9233aa6a95002705356e5b91ff2ca01316f023e14a25e11226309b33d4d48e437bacde69beef9cef90b402f0da437f4fbdb0825f25", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b65ea6e99a4fb76274b5cc0c14f67a1e321a21fda05a7856a594e8ee55b659d87f92116ef0d85485748d26f665b9d7117dfb3cd5c042ed682806eaffa3d623e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feb39c5b9e4deb8617484816dcc5f2df3b313a3754b24347667e27c48d15534a69654a7dc5c957303de9fae36faef7b166dc1e48218c909c13a8ab838b8f3bc3"} 12:44:14 INFO - Running post-run listener: copy_logs_to_upload_dir 12:44:14 INFO - Copying logs to upload dir... 12:44:14 INFO - mkdir: /builds/slave/test/build/upload/logs 12:44:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.032478 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-04-15 12:44:14.982854) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-15 12:44:14.987011) ========= 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.QzfUAG9JSe/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.b3OtLjOeo1/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7de93507e45b6655000de9dd0e5ff0c3da9c82038958c26d6d913add08b143093ce6d61a09b0c530492d5812781d1226efbf2d814d501e6597cb6001aa225cc0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cf43f22cc8ef368155e4c9233aa6a95002705356e5b91ff2ca01316f023e14a25e11226309b33d4d48e437bacde69beef9cef90b402f0da437f4fbdb0825f25", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b65ea6e99a4fb76274b5cc0c14f67a1e321a21fda05a7856a594e8ee55b659d87f92116ef0d85485748d26f665b9d7117dfb3cd5c042ed682806eaffa3d623e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feb39c5b9e4deb8617484816dcc5f2df3b313a3754b24347667e27c48d15534a69654a7dc5c957303de9fae36faef7b166dc1e48218c909c13a8ab838b8f3bc3"} build_url:https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011242 build_url: 'https://queue.taskcluster.net/v1/task/IsNa68z7SYybDnRYqaNyoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7de93507e45b6655000de9dd0e5ff0c3da9c82038958c26d6d913add08b143093ce6d61a09b0c530492d5812781d1226efbf2d814d501e6597cb6001aa225cc0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cf43f22cc8ef368155e4c9233aa6a95002705356e5b91ff2ca01316f023e14a25e11226309b33d4d48e437bacde69beef9cef90b402f0da437f4fbdb0825f25", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b65ea6e99a4fb76274b5cc0c14f67a1e321a21fda05a7856a594e8ee55b659d87f92116ef0d85485748d26f665b9d7117dfb3cd5c042ed682806eaffa3d623e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feb39c5b9e4deb8617484816dcc5f2df3b313a3754b24347667e27c48d15534a69654a7dc5c957303de9fae36faef7b166dc1e48218c909c13a8ab838b8f3bc3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-15 12:44:15.022489) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 12:44:15.022840) ========= 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.QzfUAG9JSe/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.b3OtLjOeo1/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.005223 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-15 12:44:15.075080) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-15 12:44:15.075912) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-15 12:44:15.076900) ========= ========= Total master_lag: 0.26 =========