builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0211 starttime: 1486692906.99 results: success (0) buildid: 20170209170211 builduid: eadb590b78a6436a8ffabcb183f51b85 revision: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 18:15:06.987779) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 18:15:06.988209) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2017-02-09 18:15:06.988482) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2017-02-09 18:15:12.997398) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2017-02-09 18:15:13.001947) ========= 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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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-02-09 18:15:13-- 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% 23.3M=0s 2017-02-09 18:15:13 (23.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.099631 ========= master_lag: 7.33 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2017-02-09 18:15:20.433320) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 18:15:20.433673) ========= 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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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.026674 ========= master_lag: 1.89 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 18:15:22.345974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2017-02-09 18:15:22.346372) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 25a94c1047e793ef096d8556fa3c26dd72bd37d7 --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 25a94c1047e793ef096d8556fa3c26dd72bd37d7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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-02-09 18:15:22,456 truncating revision to first 12 chars 2017-02-09 18:15:22,456 Setting DEBUG logging. 2017-02-09 18:15:22,456 attempt 1/10 2017-02-09 18:15:22,456 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/25a94c1047e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 18:15:24,666 unpacking tar archive at: mozilla-central-25a94c1047e7/testing/mozharness/ program finished with exit code 0 elapsedTime=2.490967 ========= master_lag: 5.28 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2017-02-09 18:15:30.117801) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 18:15:30.122207) ========= script_repo_revision: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 18:15:30.122625) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2017-02-09 18:15:30.122907) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2017-02-09 18:15:32.245980) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-09 18:15:32.246396) ========= /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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 18:15:32 INFO - MultiFileLogger online at 20170209 18:15:32 in /builds/slave/test 18:15:32 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 18:15:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:15:32 INFO - {'append_to_log': False, 18:15:32 INFO - 'base_work_dir': '/builds/slave/test', 18:15:32 INFO - 'blob_upload_branch': 'Firefox', 18:15:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:15:32 INFO - 'branch': 'Firefox', 18:15:32 INFO - 'buildbot_json_path': 'buildprops.json', 18:15:32 INFO - 'config_files': ('talos/mac_config.py',), 18:15:32 INFO - 'default_actions': ('clobber', 18:15:32 INFO - 'read-buildbot-config', 18:15:32 INFO - 'download-and-extract', 18:15:32 INFO - 'populate-webroot', 18:15:32 INFO - 'create-virtualenv', 18:15:32 INFO - 'install', 18:15:32 INFO - 'run-tests'), 18:15:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:15:32 INFO - 'download_minidump_stackwalk': True, 18:15:32 INFO - 'download_symbols': 'ondemand', 18:15:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:15:32 INFO - 'tooltool.py': '/tools/tooltool.py', 18:15:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:15:32 INFO - '/tools/misc-python/virtualenv.py')}, 18:15:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:15:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:15:32 INFO - 'installer_path': 'installer.exe', 18:15:32 INFO - 'log_level': 'info', 18:15:32 INFO - 'log_name': 'talos', 18:15:32 INFO - 'log_to_console': True, 18:15:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:15:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:15:32 INFO - 'opt_config_files': (), 18:15:32 INFO - 'pip_index': False, 18:15:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:15:32 INFO - 'cmd': ('bash', 18:15:32 INFO - '-c', 18:15:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:15:32 INFO - 'enabled': True, 18:15:32 INFO - 'halt_on_failure': False, 18:15:32 INFO - 'name': 'check_screen_resolution'},), 18:15:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:15:32 INFO - 'cmd': ('bash', 18:15:32 INFO - '-c', 18:15:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:15:32 INFO - 'enabled': True, 18:15:32 INFO - 'halt_on_failure': False, 18:15:32 INFO - 'name': 'check_screen_resolution'},), 18:15:32 INFO - 'run_cmd_checks_enabled': True, 18:15:32 INFO - 'sps_profile': False, 18:15:32 INFO - 'sps_profile_interval': 0, 18:15:32 INFO - 'suite': 'dromaeojs-e10s', 18:15:32 INFO - 'system_bits': '32', 18:15:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:15:32 INFO - 'title': 't-yosemite-r7-0211', 18:15:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:15:32 INFO - 'use_talos_json': True, 18:15:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:15:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:15:32 INFO - 'work_dir': 'build'} 18:15:32 INFO - [mozharness: 2017-02-10 02:15:32.447775Z] Running clobber step. 18:15:32 INFO - Running pre-action listener: _resource_record_pre_action 18:15:32 INFO - Running main action method: clobber 18:15:32 INFO - rmtree: /builds/slave/test/build 18:15:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:15:34 INFO - Running post-action listener: _resource_record_post_action 18:15:34 INFO - [mozharness: 2017-02-10 02:15:34.895342Z] Finished clobber step (success) 18:15:34 INFO - [mozharness: 2017-02-10 02:15:34.895504Z] Running read-buildbot-config step. 18:15:34 INFO - Running pre-action listener: _resource_record_pre_action 18:15:34 INFO - Running main action method: read_buildbot_config 18:15:34 INFO - Using buildbot properties: 18:15:34 INFO - { 18:15:34 INFO - "project": "", 18:15:34 INFO - "product": "firefox", 18:15:34 INFO - "script_repo_revision": "production", 18:15:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:15:34 INFO - "repository": "", 18:15:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:15:34 INFO - "buildid": "20170209170211", 18:15:34 INFO - "pgo_build": "False", 18:15:34 INFO - "basedir": "/builds/slave/test", 18:15:34 INFO - "buildnumber": 58, 18:15:34 INFO - "slavename": "t-yosemite-r7-0211", 18:15:34 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:15:34 INFO - "platform": "yosemite_r7", 18:15:34 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:15:34 INFO - "branch": "mozilla-central", 18:15:34 INFO - "repo_path": "mozilla-central", 18:15:34 INFO - "revision": "25a94c1047e793ef096d8556fa3c26dd72bd37d7", 18:15:34 INFO - "stage_platform": "macosx64", 18:15:34 INFO - "builduid": "eadb590b78a6436a8ffabcb183f51b85", 18:15:34 INFO - "slavebuilddir": "test" 18:15:34 INFO - } 18:15:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 18:15:34 INFO - Running post-action listener: _resource_record_post_action 18:15:34 INFO - [mozharness: 2017-02-10 02:15:34.898927Z] Finished read-buildbot-config step (success) 18:15:34 INFO - [mozharness: 2017-02-10 02:15:34.899039Z] Running download-and-extract step. 18:15:34 INFO - Running pre-action listener: _resource_record_pre_action 18:15:34 INFO - Running main action method: download_and_extract 18:15:34 INFO - mkdir: /builds/slave/test/build/tests 18:15: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')]} 18:15:34 INFO - trying https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 18:15:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:15:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 18:15:35 INFO - Downloaded 1199 bytes. 18:15:35 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:15:35 INFO - Using the following test package requirements: 18:15:35 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:15:35 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:15:35 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 18:15:35 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:15:35 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 18:15:35 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:15:35 INFO - u'jsshell-mac.zip'], 18:15:35 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:15:35 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 18:15:35 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:15:35 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:15:35 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 18:15:35 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:15:35 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 18:15:35 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:15:35 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 18:15:35 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:15:35 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 18:15:35 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:15:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:15:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:15:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:15:37 INFO - Expected file size: 34949027 18:15:37 INFO - Obtained file size: 34949027 18:15:43 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:15:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:15:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:15:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:15:45 INFO - Expected file size: 34949027 18:15:45 INFO - Obtained file size: 34949027 18:15:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 18:15:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:15:51 INFO - Fetch https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 18:15:53 INFO - Expected file size: 14403454 18:15:53 INFO - Obtained file size: 14403454 18:15:53 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')]} 18:15:53 INFO - trying https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:15:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 18:15:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 18:15:55 INFO - Downloaded 70348678 bytes. 18:15:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:15:55 INFO - mkdir: /builds/slave/test/properties 18:15:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:15:55 INFO - Writing to file /builds/slave/test/properties/build_url 18:15:55 INFO - Contents: 18:15:55 INFO - build_url:https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:15:56 INFO - Running post-action listener: _resource_record_post_action 18:15:56 INFO - Running post-action listener: set_extra_try_arguments 18:15:56 INFO - [mozharness: 2017-02-10 02:15:56.266254Z] Finished download-and-extract step (success) 18:15:56 INFO - [mozharness: 2017-02-10 02:15:56.266392Z] Running populate-webroot step. 18:15:56 INFO - Running pre-action listener: _resource_record_pre_action 18:15:56 INFO - Running main action method: populate_webroot 18:15:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:15:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:15:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:15:56 INFO - '--tppagecycles', 18:15:56 INFO - '7'], 18:15:56 INFO - 'tests': ['tsvgm']}}, 18:15:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:15:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:15:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:15:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:15:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:15:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:15:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:15:56 INFO - 'talos_options': ['--disable-e10s'], 18:15:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:15:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:15:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:15:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:15:56 INFO - 'talos_options': ['--disable-e10s'], 18:15:56 INFO - 'tests': ['damp', 'tps']}, 18:15:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:15:56 INFO - 'tests': ['damp', 'tps']}, 18:15:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:15:56 INFO - 'tests': ['dromaeo_dom']}, 18:15:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:15:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:15:56 INFO - 'tests': ['basic_compositor_video']}, 18:15:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:15:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:15:56 INFO - 'tests': ['a11yr', 18:15:56 INFO - 'ts_paint', 18:15:56 INFO - 'tpaint', 18:15:56 INFO - 'sessionrestore', 18:15:56 INFO - 'sessionrestore_no_auto_restore', 18:15:56 INFO - 'tabpaint']}, 18:15:56 INFO - 'other-e10s': {'tests': ['a11yr', 18:15:56 INFO - 'ts_paint', 18:15:56 INFO - 'tpaint', 18:15:56 INFO - 'sessionrestore', 18:15:56 INFO - 'sessionrestore_no_auto_restore', 18:15:56 INFO - 'tabpaint']}, 18:15:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:15:56 INFO - 'ts_paint', 18:15:56 INFO - 'tpaint', 18:15:56 INFO - 'sessionrestore', 18:15:56 INFO - 'sessionrestore_no_auto_restore', 18:15:56 INFO - 'tabpaint']}, 18:15:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:15:56 INFO - 'ts_paint', 18:15:56 INFO - 'tpaint', 18:15:56 INFO - 'sessionrestore', 18:15:56 INFO - 'sessionrestore_no_auto_restore', 18:15:56 INFO - 'tabpaint']}, 18:15:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:15:56 INFO - 'tests': ['a11yr', 18:15:56 INFO - 'ts_paint', 18:15:56 INFO - 'tpaint', 18:15:56 INFO - 'sessionrestore', 18:15:56 INFO - 'sessionrestore_no_auto_restore', 18:15:56 INFO - 'tabpaint']}, 18:15:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:15:56 INFO - 'tests': ['a11yr', 18:15:56 INFO - 'ts_paint', 18:15:56 INFO - 'tpaint', 18:15:56 INFO - 'sessionrestore', 18:15:56 INFO - 'sessionrestore_no_auto_restore', 18:15:56 INFO - 'tabpaint']}, 18:15:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:15:56 INFO - 'tests': ['tsvgx', 18:15:56 INFO - 'tsvgr_opacity', 18:15:56 INFO - 'tart', 18:15:56 INFO - 'tscrollx', 18:15:56 INFO - 'cart', 18:15:56 INFO - 'tsvg_static']}, 18:15:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:15:56 INFO - 'tsvgr_opacity', 18:15:56 INFO - 'tart', 18:15:56 INFO - 'tscrollx', 18:15:56 INFO - 'cart', 18:15:56 INFO - 'tsvg_static']}, 18:15:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:15:56 INFO - 'talos_options': ['--disable-e10s'], 18:15:56 INFO - 'tests': ['tp5o']}, 18:15:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:15:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:15:56 INFO - 'talos_options': ['--disable-e10s', 18:15:56 INFO - '--xperf_path', 18:15:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:15:56 INFO - 'tests': ['tp5n']}, 18:15:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:15:56 INFO - 'talos_options': ['--xperf_path', 18:15:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:15:56 INFO - 'tests': ['tp5n']}}, 18:15:56 INFO - 'talos.zip': {'path': '', 18:15:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:15:56 INFO - Running post-action listener: _resource_record_post_action 18:15:56 INFO - [mozharness: 2017-02-10 02:15:56.284398Z] Finished populate-webroot step (success) 18:15:56 INFO - [mozharness: 2017-02-10 02:15:56.284516Z] Running create-virtualenv step. 18:15:56 INFO - Running pre-action listener: _resource_record_pre_action 18:15:56 INFO - Running main action method: create_virtualenv 18:15:56 INFO - Creating virtualenv /builds/slave/test/build/venv 18:15:56 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 18:15:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:15:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:15:56 INFO - Using real prefix '/tools/python27' 18:15:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:15:57 INFO - Installing distribute.............................................................................................................................................................................................done. 18:15:59 INFO - Installing pip.................done. 18:15:59 INFO - Return code: 0 18:15:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:15:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:16:00 INFO - Reading from file tmpfile_stdout 18:16:00 INFO - Output received: 18:16:00 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) 18:16:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:00 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 0x106db5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106db8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb514c5650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b93d30>, '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 0x106abadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b88be8>, '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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 18:16:00 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 18:16:00 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 18:16:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bz0TqZxSSO/Render', 18:16:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:16:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:16:00 INFO - 'HOME': '/Users/cltbld', 18:16:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:16:00 INFO - 'LOGNAME': 'cltbld', 18:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:00 INFO - 'MOZ_NO_REMOTE': '1', 18:16:00 INFO - 'NO_EM_RESTART': '1', 18:16:00 INFO - 'PAGER': '/bin/cat', 18:16:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:00 INFO - 'PWD': '/builds/slave/test', 18:16:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:16:00 INFO - 'SHELL': '/bin/bash', 18:16:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zYOgG4K5qZ/Listeners', 18:16:00 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:16:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:16:00 INFO - 'USER': 'cltbld', 18:16:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:16:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:00 INFO - 'XPC_FLAGS': '0x0', 18:16:00 INFO - 'XPC_SERVICE_NAME': '0', 18:16:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:00 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 18:16:00 INFO - Cleaning up... 18:16:00 INFO - Return code: 0 18:16:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:01 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 0x106db5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106db8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb514c5650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b93d30>, '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 0x106abadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b88be8>, '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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 18:16:01 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 18:16:01 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 18:16:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bz0TqZxSSO/Render', 18:16:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:16:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:16:01 INFO - 'HOME': '/Users/cltbld', 18:16:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:16:01 INFO - 'LOGNAME': 'cltbld', 18:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:01 INFO - 'MOZ_NO_REMOTE': '1', 18:16:01 INFO - 'NO_EM_RESTART': '1', 18:16:01 INFO - 'PAGER': '/bin/cat', 18:16:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:01 INFO - 'PWD': '/builds/slave/test', 18:16:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:16:01 INFO - 'SHELL': '/bin/bash', 18:16:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zYOgG4K5qZ/Listeners', 18:16:01 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:16:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:16:01 INFO - 'USER': 'cltbld', 18:16:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:16:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:01 INFO - 'XPC_FLAGS': '0x0', 18:16:01 INFO - 'XPC_SERVICE_NAME': '0', 18:16:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:16:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:01 INFO - Downloading/unpacking psutil>=3.1.1 18:16:01 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 18:16:01 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 18:16:01 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 18:16:01 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 18:16:01 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 18:16:01 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 18:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:16:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:16:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:16:05 INFO - Installing collected packages: psutil 18:16:05 INFO - Running setup.py install for psutil 18:16:05 INFO - building 'psutil._psutil_osx' extension 18:16:05 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 18:16:06 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 18:16:06 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 18:16:06 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 18:16:06 INFO - building 'psutil._psutil_posix' extension 18:16:06 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 18:16:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:16:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:16:06 INFO - ^ 18:16:06 INFO - 1 warning generated. 18:16:06 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 18:16:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:16:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:16:06 INFO - Successfully installed psutil 18:16:06 INFO - Cleaning up... 18:16:06 INFO - Return code: 0 18:16:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:16:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:07 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')]} 18:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:07 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 0x106db5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106db8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb514c5650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b93d30>, '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 0x106abadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b88be8>, '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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 18:16:07 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 18:16:07 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 18:16:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bz0TqZxSSO/Render', 18:16:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:16:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:16:07 INFO - 'HOME': '/Users/cltbld', 18:16:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:16:07 INFO - 'LOGNAME': 'cltbld', 18:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:07 INFO - 'MOZ_NO_REMOTE': '1', 18:16:07 INFO - 'NO_EM_RESTART': '1', 18:16:07 INFO - 'PAGER': '/bin/cat', 18:16:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:07 INFO - 'PWD': '/builds/slave/test', 18:16:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:16:07 INFO - 'SHELL': '/bin/bash', 18:16:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zYOgG4K5qZ/Listeners', 18:16:07 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:16:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:16:07 INFO - 'USER': 'cltbld', 18:16:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:16:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:07 INFO - 'XPC_FLAGS': '0x0', 18:16:07 INFO - 'XPC_SERVICE_NAME': '0', 18:16:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:16:07 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 18:16:07 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 18:16:07 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 18:16:07 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 18:16:07 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 18:16:07 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 18:16:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:16:10 INFO - Installing collected packages: mozsystemmonitor 18:16:10 INFO - Running setup.py install for mozsystemmonitor 18:16:10 INFO - Successfully installed mozsystemmonitor 18:16:10 INFO - Cleaning up... 18:16:10 INFO - Return code: 0 18:16:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106db5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106db8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb514c5650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b93d30>, '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 0x106abadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b88be8>, '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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 18:16:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:16:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:16:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bz0TqZxSSO/Render', 18:16:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:16:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:16:10 INFO - 'HOME': '/Users/cltbld', 18:16:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:16:10 INFO - 'LOGNAME': 'cltbld', 18:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:10 INFO - 'MOZ_NO_REMOTE': '1', 18:16:10 INFO - 'NO_EM_RESTART': '1', 18:16:10 INFO - 'PAGER': '/bin/cat', 18:16:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:10 INFO - 'PWD': '/builds/slave/test', 18:16:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:16:10 INFO - 'SHELL': '/bin/bash', 18:16:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zYOgG4K5qZ/Listeners', 18:16:10 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:16:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:16:10 INFO - 'USER': 'cltbld', 18:16:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:16:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:10 INFO - 'XPC_FLAGS': '0x0', 18:16:10 INFO - 'XPC_SERVICE_NAME': '0', 18:16:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:16:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:10 INFO - Downloading/unpacking jsonschema==2.5.1 18:16:10 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 18:16:10 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 18:16:10 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 18:16:10 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 18:16:10 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 18:16:10 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 18:16:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:16:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:16:13 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 18:16:13 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 18:16:13 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 18:16:13 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 18:16:13 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 18:16:13 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 18:16:13 INFO - Downloading functools32-3.2.3-2.tar.gz 18:16:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:16:13 INFO - warning: no files found matching '*.txt' 18:16:13 INFO - no previously-included directories found matching 'build' 18:16:13 INFO - no previously-included directories found matching 'dist' 18:16:13 INFO - no previously-included directories found matching '.git*' 18:16:13 INFO - Installing collected packages: jsonschema, functools32 18:16:13 INFO - Running setup.py install for functools32 18:16:13 INFO - warning: no files found matching '*.txt' 18:16:13 INFO - no previously-included directories found matching 'build' 18:16:13 INFO - no previously-included directories found matching 'dist' 18:16:13 INFO - no previously-included directories found matching '.git*' 18:16:14 INFO - Successfully installed jsonschema functools32 18:16:14 INFO - Cleaning up... 18:16:14 INFO - Return code: 0 18:16:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:16:14 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')]} 18:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:14 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')]} 18:16:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:14 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 0x106db5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106db8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb514c5650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b93d30>, '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 0x106abadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b88be8>, '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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 18:16:14 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 18:16:14 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 18:16:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bz0TqZxSSO/Render', 18:16:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:16:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:16:14 INFO - 'HOME': '/Users/cltbld', 18:16:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:16:14 INFO - 'LOGNAME': 'cltbld', 18:16:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:14 INFO - 'MOZ_NO_REMOTE': '1', 18:16:14 INFO - 'NO_EM_RESTART': '1', 18:16:14 INFO - 'PAGER': '/bin/cat', 18:16:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:16:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:14 INFO - 'PWD': '/builds/slave/test', 18:16:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:16:14 INFO - 'SHELL': '/bin/bash', 18:16:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zYOgG4K5qZ/Listeners', 18:16:14 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:16:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:16:14 INFO - 'USER': 'cltbld', 18:16:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:16:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:16:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:14 INFO - 'XPC_FLAGS': '0x0', 18:16:14 INFO - 'XPC_SERVICE_NAME': '0', 18:16:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:16:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:16:14 INFO - Cleaning up... 18:16:14 INFO - Return code: 0 18:16:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:16:14 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')]} 18:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:14 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')]} 18:16:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:14 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 0x106db5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106db8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb514c5650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b93d30>, '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 0x106abadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b88be8>, '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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 18:16:14 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 18:16:14 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 18:16:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bz0TqZxSSO/Render', 18:16:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:16:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:16:14 INFO - 'HOME': '/Users/cltbld', 18:16:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:16:14 INFO - 'LOGNAME': 'cltbld', 18:16:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:14 INFO - 'MOZ_NO_REMOTE': '1', 18:16:14 INFO - 'NO_EM_RESTART': '1', 18:16:14 INFO - 'PAGER': '/bin/cat', 18:16:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:16:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:14 INFO - 'PWD': '/builds/slave/test', 18:16:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:16:14 INFO - 'SHELL': '/bin/bash', 18:16:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zYOgG4K5qZ/Listeners', 18:16:14 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:16:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:16:14 INFO - 'USER': 'cltbld', 18:16:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:16:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:16:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:14 INFO - 'XPC_FLAGS': '0x0', 18:16:14 INFO - 'XPC_SERVICE_NAME': '0', 18:16:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:16:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:14 INFO - Downloading/unpacking blobuploader==1.2.4 18:16:14 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 18:16:14 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 18:16:14 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 18:16:14 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 18:16:14 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 18:16:14 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 18:16:17 INFO - Downloading blobuploader-1.2.4.tar.gz 18:16:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:16:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:16:17 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 18:16:17 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 18:16:17 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 18:16:17 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 18:16:17 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 18:16:17 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 18:16:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:16:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:18 INFO - Downloading docopt-0.6.1.tar.gz 18:16:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:16:18 INFO - Installing collected packages: blobuploader, requests, docopt 18:16:18 INFO - Running setup.py install for blobuploader 18:16:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:16:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:16:18 INFO - Running setup.py install for requests 18:16:19 INFO - Running setup.py install for docopt 18:16:19 INFO - Successfully installed blobuploader requests docopt 18:16:19 INFO - Cleaning up... 18:16:19 INFO - Return code: 0 18:16:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:19 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 0x106db5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106db8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb514c5650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b93d30>, '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 0x106abadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b88be8>, '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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 18:16:19 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 18:16:19 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 18:16:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bz0TqZxSSO/Render', 18:16:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:16:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:16:19 INFO - 'HOME': '/Users/cltbld', 18:16:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:16:19 INFO - 'LOGNAME': 'cltbld', 18:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:19 INFO - 'MOZ_NO_REMOTE': '1', 18:16:19 INFO - 'NO_EM_RESTART': '1', 18:16:19 INFO - 'PAGER': '/bin/cat', 18:16:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:19 INFO - 'PWD': '/builds/slave/test', 18:16:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:16:19 INFO - 'SHELL': '/bin/bash', 18:16:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zYOgG4K5qZ/Listeners', 18:16:19 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:16:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:16:19 INFO - 'USER': 'cltbld', 18:16:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:16:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:19 INFO - 'XPC_FLAGS': '0x0', 18:16:19 INFO - 'XPC_SERVICE_NAME': '0', 18:16:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:16:19 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-E9P0tW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:16:19 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-7BiLfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:16:20 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-g7ngp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:16:20 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-NI8ZLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:16:20 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-uia2pT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:16:20 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-wVzrGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:16:20 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-AenzxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:16:20 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-35n4s6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:16:20 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-q5DBQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:16:20 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-6PU3u7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:16:20 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-ZeWbf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:16:20 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-61m6hG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:16:21 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-0ORwFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:16:21 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-8qnJQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:21 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-KAZQ5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:16:21 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-upfKTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:16:21 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-dyJksk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:16:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:16:21 INFO - Running setup.py install for manifestparser 18:16:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:16:21 INFO - Running setup.py install for mozcrash 18:16:21 INFO - Running setup.py install for mozdebug 18:16:22 INFO - Running setup.py install for mozdevice 18:16:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:16:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:16:22 INFO - Running setup.py install for mozfile 18:16:22 INFO - Running setup.py install for mozhttpd 18:16:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:16:22 INFO - Running setup.py install for mozinfo 18:16:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:16:22 INFO - Running setup.py install for mozInstall 18:16:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:16:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:16:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:16:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:16:22 INFO - Running setup.py install for mozleak 18:16:22 INFO - Running setup.py install for mozlog 18:16:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:16:23 INFO - Running setup.py install for moznetwork 18:16:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:16:23 INFO - Running setup.py install for mozprocess 18:16:23 INFO - Running setup.py install for mozprofile 18:16:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:16:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:16:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:16:23 INFO - Running setup.py install for mozrunner 18:16:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:16:23 INFO - Running setup.py install for mozscreenshot 18:16:23 INFO - Running setup.py install for moztest 18:16:24 INFO - Running setup.py install for mozversion 18:16:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:16:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:16:24 INFO - Cleaning up... 18:16:24 INFO - Return code: 0 18:16:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:24 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')]} 18:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:24 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')]} 18:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:24 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 0x106db5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106db8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb514c5650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b93d30>, '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 0x106abadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b88be8>, '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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 18:16:24 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 18:16:24 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 18:16:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bz0TqZxSSO/Render', 18:16:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:16:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:16:24 INFO - 'HOME': '/Users/cltbld', 18:16:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:16:24 INFO - 'LOGNAME': 'cltbld', 18:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:24 INFO - 'MOZ_NO_REMOTE': '1', 18:16:24 INFO - 'NO_EM_RESTART': '1', 18:16:24 INFO - 'PAGER': '/bin/cat', 18:16:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:24 INFO - 'PWD': '/builds/slave/test', 18:16:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:16:24 INFO - 'SHELL': '/bin/bash', 18:16:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zYOgG4K5qZ/Listeners', 18:16:24 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:16:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:16:24 INFO - 'USER': 'cltbld', 18:16:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:16:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:24 INFO - 'XPC_FLAGS': '0x0', 18:16:24 INFO - 'XPC_SERVICE_NAME': '0', 18:16:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:16:24 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-KJz8n0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:16:24 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)) 18:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:16:24 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-etnCMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:16:24 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)) 18:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:16:24 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-9OOXqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:16:24 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)) 18:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:16:24 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-uNkacQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:16:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:16:24 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-VRIWGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:16:25 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)) 18:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:16:25 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-tCRLd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:16:25 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)) 18:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:16:25 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-6dMPOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:16:25 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)) 18:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:16:25 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-aq3C5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:16:25 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)) 18:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:16:25 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-KBhbQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:16:25 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)) 18:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:16:25 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-rZL0Co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:16:25 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)) 18:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:16:25 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-u_vVzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:16:25 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)) 18:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:16:25 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-H2CU11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:16:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 18:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:16:25 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-8dYNhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:16:25 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)) 18:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:16:25 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-XMmjOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:16:26 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)) 18:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:26 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-sogg94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:26 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)) 18:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:16:26 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-yoiwWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:16:26 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)) 18:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:16:26 INFO - Running setup.py (path:/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/pip-OdAs8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:16:26 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)) 18:16:26 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)) 18:16:26 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)) 18:16:26 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)) 18:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:16:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:28 INFO - Downloading blessings-1.6.tar.gz 18:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:16:28 INFO - Installing collected packages: blessings 18:16:28 INFO - Running setup.py install for blessings 18:16:29 INFO - Successfully installed blessings 18:16:29 INFO - Cleaning up... 18:16:29 INFO - Return code: 0 18:16:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:16:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:16:29 INFO - Reading from file tmpfile_stdout 18:16:29 INFO - Current package versions: 18:16:29 INFO - blessings == 1.6 18:16:29 INFO - blobuploader == 1.2.4 18:16:29 INFO - docopt == 0.6.1 18:16:29 INFO - functools32 == 3.2.3-2 18:16:29 INFO - jsonschema == 2.5.1 18:16:29 INFO - manifestparser == 1.1 18:16:29 INFO - mozInstall == 1.12 18:16:29 INFO - mozcrash == 1.0 18:16:29 INFO - mozdebug == 0.1 18:16:29 INFO - mozdevice == 0.49 18:16:29 INFO - mozfile == 1.2 18:16:29 INFO - mozhttpd == 0.7 18:16:29 INFO - mozinfo == 0.9 18:16:29 INFO - mozleak == 0.1 18:16:29 INFO - mozlog == 3.4 18:16:29 INFO - moznetwork == 0.27 18:16:29 INFO - mozprocess == 0.24 18:16:29 INFO - mozprofile == 0.28 18:16:29 INFO - mozrunner == 6.13 18:16:29 INFO - mozscreenshot == 0.1 18:16:29 INFO - mozsystemmonitor == 0.3 18:16:29 INFO - moztest == 0.8 18:16:29 INFO - mozversion == 1.4 18:16:29 INFO - psutil == 3.1.1 18:16:29 INFO - requests == 1.2.3 18:16:29 INFO - wsgiref == 0.1.2 18:16:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:29 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 0x106db5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106db8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb514c5650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b93d30>, '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 0x106abadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b88be8>, '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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 18:16:29 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 18:16:29 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 18:16:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bz0TqZxSSO/Render', 18:16:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:16:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:16:29 INFO - 'HOME': '/Users/cltbld', 18:16:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:16:29 INFO - 'LOGNAME': 'cltbld', 18:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:29 INFO - 'MOZ_NO_REMOTE': '1', 18:16:29 INFO - 'NO_EM_RESTART': '1', 18:16:29 INFO - 'PAGER': '/bin/cat', 18:16:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:29 INFO - 'PWD': '/builds/slave/test', 18:16:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:16:29 INFO - 'SHELL': '/bin/bash', 18:16:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zYOgG4K5qZ/Listeners', 18:16:29 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:16:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:16:29 INFO - 'USER': 'cltbld', 18:16:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:16:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:29 INFO - 'XPC_FLAGS': '0x0', 18:16:29 INFO - 'XPC_SERVICE_NAME': '0', 18:16:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:29 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)) 18:16:29 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)) 18:16:29 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)) 18:16:29 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)) 18:16:29 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)) 18:16:29 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)) 18:16:29 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)) 18:16:29 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)) 18:16:29 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)) 18:16:29 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)) 18:16:29 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)) 18:16:29 INFO - Cleaning up... 18:16:29 INFO - Return code: 0 18:16:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106db5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106db8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb514c5650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b93d30>, '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 0x106abadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b88be8>, '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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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 18:16:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:16:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bz0TqZxSSO/Render', 18:16:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:16:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:16:29 INFO - 'HOME': '/Users/cltbld', 18:16:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:16:29 INFO - 'LOGNAME': 'cltbld', 18:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:29 INFO - 'MOZ_NO_REMOTE': '1', 18:16:29 INFO - 'NO_EM_RESTART': '1', 18:16:29 INFO - 'PAGER': '/bin/cat', 18:16:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:29 INFO - 'PWD': '/builds/slave/test', 18:16:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:16:29 INFO - 'SHELL': '/bin/bash', 18:16:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zYOgG4K5qZ/Listeners', 18:16:29 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:16:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:16:29 INFO - 'USER': 'cltbld', 18:16:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:16:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:29 INFO - 'XPC_FLAGS': '0x0', 18:16:29 INFO - 'XPC_SERVICE_NAME': '0', 18:16:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:16:30 INFO - Cleaning up... 18:16:30 INFO - Return code: 0 18:16:30 INFO - Running post-action listener: _resource_record_post_action 18:16:30 INFO - Running post-action listener: _start_resource_monitoring 18:16:30 INFO - Starting resource monitoring. 18:16:30 INFO - [mozharness: 2017-02-10 02:16:30.105304Z] Finished create-virtualenv step (success) 18:16:30 INFO - [mozharness: 2017-02-10 02:16:30.106189Z] Running install step. 18:16:30 INFO - Running pre-action listener: _resource_record_pre_action 18:16:30 INFO - Running main action method: install 18:16:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:16:30 INFO - Reading from file tmpfile_stdout 18:16:30 INFO - Detecting whether we're running mozinstall >=1.0... 18:16:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:16:30 INFO - Reading from file tmpfile_stdout 18:16:30 INFO - Output received: 18:16:30 INFO - Usage: mozinstall [options] installer 18:16:30 INFO - Options: 18:16:30 INFO - -h, --help show this help message and exit 18:16:30 INFO - -d DEST, --destination=DEST 18:16:30 INFO - Directory to install application into. [default: 18:16:30 INFO - "/builds/slave/test"] 18:16:30 INFO - --app=APP Application being installed. [default: firefox] 18:16:30 INFO - mkdir: /builds/slave/test/build/application 18:16:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:16:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:16:52 INFO - Reading from file tmpfile_stdout 18:16:52 INFO - Output received: 18:16:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:16:52 INFO - Running post-action listener: _resource_record_post_action 18:16:52 INFO - [mozharness: 2017-02-10 02:16:52.903346Z] Finished install step (success) 18:16:52 INFO - [mozharness: 2017-02-10 02:16:52.903450Z] Running run-tests step. 18:16:52 INFO - Running pre-action listener: _resource_record_pre_action 18:16:52 INFO - Running main action method: run_tests 18:16:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:16:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:16:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:16:52 INFO - 2017-02-09 18:16:52.916 screenresolution[1621:7348] starting screenresolution argv=screenresolution get 18:16:52 INFO - 2017-02-09 18:16:52.919 screenresolution[1621:7348] Display 0: 1600x1200x32@60 18:16:52 INFO - 2017-02-09 18:16:52.924 screenresolution[1622:7351] starting screenresolution argv=screenresolution list 18:16:52 INFO - Available Modes on Display 0 18:16:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:16:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:16:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:16:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:16:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:16:53 INFO - Intel Iris: 18:16:53 INFO - Chipset Model: Intel Iris 18:16:53 INFO - Type: GPU 18:16:53 INFO - Bus: Built-In 18:16:53 INFO - VRAM (Dynamic, Max): 1536 MB 18:16:53 INFO - Vendor: Intel (0x8086) 18:16:53 INFO - Device ID: 0x0a2e 18:16:53 INFO - Revision ID: 0x0009 18:16:53 INFO - Displays: 18:16:53 INFO - Display: 18:16:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:16:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:16:53 INFO - Display Serial Number: 1600x1200 60 18:16:53 INFO - Main Display: Yes 18:16:53 INFO - Mirror: Off 18:16:53 INFO - Online: Yes 18:16:53 INFO - Rotation: Supported 18:16:53 INFO - Return code: 0 18:16:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:16:53 INFO - Python 2.7.3 18:16:53 INFO - Return code: 0 18:16:53 INFO - grabbing minidump binary from tooltool 18:16:53 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')]} 18:16:53 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 0x106b93d30>, '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 0x106abadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b88be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:16:53 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 18:16:53 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 18:16:53 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 18:16:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:16:53 INFO - Return code: 0 18:16:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:16:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:16:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:16:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:16:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:16:53 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/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0211', '--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 18:16:53 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/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0211 --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 18:16:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bz0TqZxSSO/Render', 18:16:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:16:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:16:53 INFO - 'HOME': '/Users/cltbld', 18:16:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:16:53 INFO - 'LOGNAME': 'cltbld', 18:16:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:16:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:53 INFO - 'MOZ_NO_REMOTE': '1', 18:16:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:16:53 INFO - 'NO_EM_RESTART': '1', 18:16:53 INFO - 'PAGER': '/bin/cat', 18:16:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:53 INFO - 'PWD': '/builds/slave/test', 18:16:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:16:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:16:53 INFO - 'SHELL': '/bin/bash', 18:16:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zYOgG4K5qZ/Listeners', 18:16:53 INFO - 'TMPDIR': '/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/', 18:16:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:16:53 INFO - 'USER': 'cltbld', 18:16:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:16:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:53 INFO - 'XPC_FLAGS': '0x0', 18:16:53 INFO - 'XPC_SERVICE_NAME': '0', 18:16:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:16:53 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/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0211', '--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 18:16:53 INFO - mozversion application_buildid: 20170209170211 18:16:53 INFO - mozversion application_changeset: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 18:16:53 INFO - mozversion application_display_name: Nightly 18:16:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:16:53 INFO - mozversion application_name: Firefox 18:16:53 INFO - mozversion application_remotingname: firefox 18:16:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:16:53 INFO - mozversion application_vendor: Mozilla 18:16:53 INFO - mozversion application_version: 54.0a1 18:16:53 INFO - mozversion platform_buildid: 20170209170211 18:16:53 INFO - mozversion platform_changeset: 25a94c1047e793ef096d8556fa3c26dd72bd37d7 18:16:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:16:53 INFO - mozversion platform_version: 54.0a1 18:16:53 INFO - using testdate: 1486693013 18:16:53 INFO - actual date: 1486693013 18:16:53 INFO - starting webserver on 'localhost:49237' 18:16:53 INFO - SUITE-START | Running 2 tests 18:16:53 INFO - TEST-START | dromaeo_css 18:16:53 INFO - operating with platform_type : mac_ 18:16:53 INFO - Initialising browser for dromaeo_css test... 18:16:53 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpXQe4wp/profile http://localhost:49237/getInfo.html) 18:16:56 INFO - PROCESS | 1632 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 18:16:56 INFO - PROCESS | 1632 | 2017-02-09 18:16:56.382 plugin-container[1634:7515] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 18:16:56 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:16:56 INFO - PROCESS | 1632 | 2017-02-09 18:16:56.385 plugin-container[1634:7515] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 18:16:56 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:16:56 INFO - PROCESS | 1632 | 2017-02-09 18:16:56.385 plugin-container[1634:7515] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:16:56 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 18:16:56 INFO - PROCESS | 1632 | colorDepth:24 18:16:56 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 18:16:56 INFO - PROCESS | 1632 | __metrics 18:16:56 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 18:16:57 INFO - TEST-INFO | 1632: exit 0 18:16:57 INFO - Browser initialized. 18:16:57 INFO - Running cycle 1/1 for dromaeo_css test... 18:16:57 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpXQe4wp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:16:59 INFO - PROCESS | 1635 | 2017-02-09 18:16:59.786 plugin-container[1636:7745] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.tsm.portname' 18:16:59 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:16:59 INFO - PROCESS | 1635 | 2017-02-09 18:16:59.787 plugin-container[1636:7745] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 18:16:59 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:16:59 INFO - PROCESS | 1635 | 2017-02-09 18:16:59.787 plugin-container[1636:7745] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:17:09 INFO - PROCESS | 1635 | RSS: Main: 233558016 18:17:09 INFO - PROCESS | 1635 | 18:20:12 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 18:20:13 INFO - PROCESS | 1635 | RSS: Main: 259809280 18:20:13 INFO - PROCESS | 1635 | 18:21:57 INFO - PROCESS | 1635 | ************************* 18:21:57 INFO - PROCESS | 1635 | A coding exception was thrown and uncaught in a Task. 18:21:57 INFO - PROCESS | 1635 | 18:21:57 ERROR - PROCESS | 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:21:57 INFO - PROCESS | 1635 | Full stack: 18:21:57 INFO - PROCESS | 1635 | ************************* 18:23:16 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 18:23:17 INFO - PROCESS | 1635 | RSS: Main: 266686464 18:23:17 INFO - PROCESS | 1635 | 18:26:18 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 18:26:19 INFO - PROCESS | 1635 | RSS: Main: 278315008 18:26:19 INFO - PROCESS | 1635 | 18:29:21 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 18:29:22 INFO - PROCESS | 1635 | RSS: Main: 272887808 18:29:22 INFO - PROCESS | 1635 | 18:32:25 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 18:32:25 INFO - PROCESS | 1635 | RSS: Main: 277970944 18:32:25 INFO - PROCESS | 1635 | 18:35:28 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 18:35:28 INFO - PROCESS | 1635 | RSS: Main: 279789568 18:35:28 INFO - PROCESS | 1635 | 18:35:28 INFO - PROCESS | 1635 | __start_tp_report 18:35:28 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 18:35:28 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 18:35:28 INFO - PROCESS | 1635 | |i|pagename|runs| 18:35:28 INFO - PROCESS | 1635 | |0;dojo.html;3290.8354582270895;3314.5691060162176;3341.198112453012;3360.781549199302;3367.191873950251;5373.704446255454;5460.426655201206;5468.015757163714;5482.835514934548;5584.776608935648;5349.946500534994;5477.75350109245;5481.369642491105;5486.039943009967;5500.972495137528;4990.126727822635;5019.749012549376;5045.142325804603;5102.846914592568;5116.283720279164;5027.673201241926;5113.872153196181;5114.872128196787;5116.692998420088;5120.129577971735;5106.31064806252;5108.259302401149;5109.386873575158;5111.7699703513435;5129.025485157827;4512.9097418051615;5100.668456550312;5103.846884593431;5110.872228194306;5127.333446651949;4943.876403089916;5055.69665820052;5063.797448102072;5066.493350664941;5066.544011039025;5373.1402975524115;5408.323959505062;5435.0488664483955;5472.179173124024;5505.752241149158;5704.942950570483;5815.302163740336;5849.590528662995;5850.0932355484965;5887.205227294303;5883.588148829584;5950;5978.133170690269;6078.8784224315705;6084.087386891957;5681.715914204273;5694.572907031948;5706.229658996024;5707.77168913243;5727.570432217601;6212.285587157445;6252;6268.373162683786;6276.278228003746;6285.182926219653;5725.971370143206;5751.309842818889;5826.883462330729;5941.197938278364;5990.7903223386975;5110.335656364723;6120.816375508742;6157.907631385533;6171.6297022178815;6195;4895.290182923527;6005.969970150209;6056.818295451144;6092.786752463642;6094.329623741473;5778.306603207642;5789.060664677296;6125.234345706786;6145.692715364214;6147.477464415575;4851.611871050308;4859.101066302675;4860.854174374775;4890.290907818347;4923.236898280772;3960.5049368828895;4204.327307630764;4361.978190109029;4367.912641747147;4374.081442896963;4814.061258054716;5656.547476201894;5671.376148623648;5677.261955945701;5677.659340439504;4682.789274482657;5908.320543137508;5942.078977758454;6002.729877155539;6073;4295.441592593005;5441.863953401117;5588.832335029955;5594.916076258859;5635.154726791015;5151.4333423323405;5190.2214667800135;5223.634345595848;5225.3207083079715;5230.293910322058;6225;6313.589616674902;6316.873662526632;6321.3564473236365;6342.302346741947;6069.969650151721;6150.538709596887;6162.907556386568;6174.043023331391;6218;6241.095041218907;6241.906371404341;6268;6271.78048768291;6291.748330066746;5559.499645031805;5777.740001700017;5811.418858114154;6081.543884208613;6103.084537319438;6032.552187475062;6079.118527813531;6093.238345206849;6115.788007244199;6123.938760612336;5860.472557469848;5880.882382352414;5884.176215329771;5902.026165682614;5909.143174239625;5667.716614169192;5879.559033072451;5887.293524777054;5900.557458190567;5906.350301466921;4746.382970213988;5392.919106213331;5530.225768392509;5691.23168372281;5802.564807639528;5833.212516310244;5887.793927212527;5898.705064746831;5913.438223368773;5931.6441013539325;5725.5992080555625;5744.798932037359;5753.3958934311295;5757.769689212385;5761.107028410603 18:35:28 INFO - PROCESS | 1635 | |1;ext.html;423.62297555175894;488.85334399680085;490.31356101457976;511.3224976905599;516.3158814570695;38858.697010754084;39754.403683944765;39855.2028959421;39928.401073983914;40050.19899602006;40101.598984010154;40240.19519609606;40585;40616;40690.18619627606;9125.863112053341;9370.625174992992;9385;10404.843927341097;10698.983596558315;10659.520321585509;10792.946035269813;10799.622013229538;10807.4055926924;10823.729406764855;10838.241323107386;10871;10872.565097396095;10892.074173695246;10893.183011274186;10758.623448179276;10805.891941080647;10823.188260880386;10824.350538967648;10850.457477126112;10508.94745526275;10601.204909631731;10611.734706632396;10615.203859710475;10650.25448218625;35980.64019359825;36034.91895243147;36166.27667446663;36228.45657315142;36251.09372265688;3554.680078792921;3621.94567081494;3706.666400023984;3708.165662725892;3871.0709429736944;23417;27089.32276693079;27265.591016134775;27292.863535682394;27716;2303.689001984744;2701.0411303987;2889.754370878478;3009;3010.277533391992;455225;460062;461670;461671;462473.68763156305;46299.5370046302;47250.76374618105;53963;54494;56935.71532142313;49038;50637.98724025572;55567;57240;57480;14441.49454769078;14532.200728959797;14551.927240363942;14593.197374144524;14596.854031459761;8904.465732056098;11103.944480277547;11214.943925280322;11215.94392028051;11338.866113388594;11961.46173422198;16874.831251687327;16972.49082527526;16989.320427183022;17103.572410689583;6254.65450456079;7428.068702137375;7433.331000210007;7440.73957411488;7489.3412838442655;788.1960400391569;828.1470085811624;831.6631764135535;836.2473773603805;858.9098144694717;23846.7615323848;29133.85433072821;29165.708342916725;29316;29640.962566310507;990.6136606723384;1006.5068116622803;1021.6781713760141;1029.7219750667277;1172.9178957472895;15236.009659372105;15252.694946101015;15279.923600381928;15391.384344626089;15485.535433937;518958;522366;523298;523872;525717;56589.43410565924;57016;57256.42743572594;57362.7131864338;57423.13865292024;1134.7843909657304;1311.0560396514684;1356.1998420931698;1361.5711051019096;1372.6774208061097;27141.321466963484;28106.2973425666;28279.01023464169;28394.71605283921;29151.271218219714;38760.22479550337;46311.53688463207;49217.2617410732;49375.25937110875;49541;537517;543527;543890;544994;545204;24930.875345623157;25499;25522.23433297004;26394.73605263923;36629.63370366262;40288.59711402848;40739.59260407358;41097.794511027256;41480.1703965913;41881.1623767529;27524.311892202855;34133;34348.82825585856;35288.11779705528;36845.44731828971;33569.83215083909;33825.323493530486;34684;34902.60389584388;35331.82334088313;33744.32511349808;33837;33994.490082649274;34002.829985849916;34300.31399371949;27542.862285688443;29246.415071698022;29915.55126673145;30224.69775302307;30279.848600756854;22945.217456471368;23008.4247893804;23071;23296.883515582314;23395.76604234004 18:35:28 INFO - PROCESS | 1635 | |2;jquery.html;365.4116871836341;369.79291596705843;377.10813925067214;378.98484060637577;399.15578551363876;18533.07334633268;19106.522336941583;19113.808861911377;19824.20703171875;20019.199232030736;11590.768184636303;11996.940015299911;12002.51989920402;12006.27962322259;12023.939880300586;6268.341824108471;6584.341565843425;6683.866322673543;6688.461653819624;6696;6594.769183078593;6722;7259.782206533812;7661.157272699998;7684.462087653867;7529.360004399633;7645;7770.494917830351;7779.611019449033;7803.375729941619;7030.859382812315;7292.307230813064;7631.236876312352;7773.7279195228175;7798.649060792278;7354.44841636874;7629.656665450068;7669.654865531064;7672.808179795494;7677.923220767777;21855;27499;27683.169504914884;28174.859125704446;28321.291967700767;2746.4644394343113;2788.60959465675;2840.4603125406106;2860.256333353343;2906.89825856094;16268.51194464168;16415.753763693556;16460.341586336534;16789.90865593745;17251.223694933757;1945.9708104378446;2007.1268997985776;2019.9899000504904;2162.770097439106;2203.986166363459;149807;153572;155694.22152889275;155931.2203438987;156777;20992.895035524725;22945.37838229611;23056.308310750705;23090.422739431313;23288.808014557202;39034.609653903666;39040.80479597641;39117.80441097776;39167.02082447905;39338.40992385116;10275.588976441008;10534.473276336152;10705.518251678694;10722.303050301705;10746;8497;8509.829803403898;8511.957440212884;8670.26302764272;8681.782955426039;10870.673879783619;10972.561097556169;10977.066949309397;11054.778904421866;11085.445727713583;4070.61329173728;4154.6399461601495;4166.7082904608305;4182.623563879205;4184.184084103571;933.2487347685043;933.6265493802533;942.820864035831;972.2659392158874;978.1441238915949;16818.831811681972;18510.81489185118;18620.16209270586;19806.50483737918;19911.203551857765;969.3941286695815;974.0843607009389;988.7379844341256;1001.7996400719885;1004.7186787699455;8982.595783189772;10068.647597334058;10258.538365773558;10284.742881427874;10339.741506462247;656815;685461;693087;700072;703484;126430;142642.43393915027;145587;148651;150385;892.1569117184199;948.4878165790534;971.9854202186973;994.1152374386946;996.3274789517047;13582.660433489242;17490.73763893568;17589.296428143243;18298.634027319644;18481.445556633324;42796.14407711891;51067.46797595929;53874.461255386945;54339.72830135824;56601.43398565962;68650.65674671595;81411.15060972837;82192.58903705444;82259;82458.76311855447;5685.886282274414;5950.821475355746;8082.555459449787;8098.433109682265;8205.671773129247;10618.415987120783;11852.229605075643;12042.63872083839;12130.090243231616;12371.381430928597;8820.911790882266;10199.490025498844;10406.843897341698;10825.350478971286;10865.565377384815;6065.6663883486835;10305.793884122426;10413.9479302603;10684.83972740425;10788.02907738276;9152.130547598234;10557.63048293306;10772.838407424051;11271.718207044889;11460.255083419552;8017.158198389086;8082.110967793656;8118.147594502492;8165.79585510367;8199.262066414052;9802.607895684092;10408.063274305348;10707;10745.731356716145;10798 18:35:28 INFO - PROCESS | 1635 | |3;mootools.html;1372.9107608005486;1386.5493714542763;1387.4658256571217;1396.8114304568883;1405.4378248700518;7864.803379915505;7969.681212751498;8241.793955151124;8294.668213271461;8301.792455188623;8163.959180204106;8204.548749818758;8261.421700480954;8288.46125001876;8303.211194936472;2180.890955452225;2212.4911270407815;2212.7012853264846;2213.4245096275004;2220.0342850859847;2170.9891450542805;2195.5938151441956;2204.151401710337;2213.966790498144;2215.8116560092412;2191.638379667353;2195.7913998170147;2221.8111460525793;2223.1552010236087;2223.977760222393;2169.327508472371;2194.0784870354328;2195.5938151442033;2196.121551379461;2202.262242148874;1651.1414064686417;1956.0341856434193;2132.7334083239593;2221.244776775904;2234.340869443518;8048.195180481964;8219.753407397788;8256.545889976049;8299;8319.292860106898;4894.975525122387;4947.455779864248;4986.351774269358;5041.647084704072;5043.571296439807;3238.3199528098894;3330.9000729977897;3362.865485380582;3388.8644454221803;3401.795892246473;1150.6433005768238;1171.794935886225;1176.7999440095205;1185.851768528934;1219.6402061391912;10388.428636424993;10418.322809017389;10435.791284174275;10492.895071049268;10505.999999999924;3689.206820533598;3945.743526670757;4244.236037513216;4255.29787585045;4300.182965236658;8409.873851892227;8449.155084491624;8483.74548763538;8494.575271236414;8506.744797655956;4881.316615673809;4893.804247830047;4898.975505122452;4901.803927842846;4913.459519452857;4346.869593912188;4532.070925460339;4575.336576196433;4584.816607335669;4588.197065513522;3419.111031131874;3429.4341433663667;3448.2241495663275;3492;3507.6667716566885;1077.6497638267595;1086.163653986426;1086.875009373932;1101.9724506887392;1103.4441437572984;755.0825746717765;786.4494853602387;896.6368620708623;901.2474583722534;926.9351145419756;5430.5927055471;5650.886982260414;5690.430956904359;5721.5136713379;5896.204012458349;797.5533701127272;909.2226146644524;917.6604656277086;951.9286053546011;975.0834215837232;3850.383938569815;3882.8446862125197;3899.707521935866;3909.7458665186673;3966.2464131814863;9660.71017869465;9723.513824308757;9828.803423931482;9883.950580247196;9887.258455615858;9253.167214950687;9746.415215087118;9820.361676491006;9845.261605379625;9850.50307140969;6416.422521973045;7543.698252069856;7732;7734.7679569613;7763.107242667165;4831.227003679394;5296.549793267462;5571;5637.126245431965;5643.746031428514;8330;8374.958125209336;8756.693515726918;8920.286377089948;9010.594523246338;8997.82004359896;9006.774830629025;9028.36801423894;9050.818983620422;9108.863367049367;6861.656917154222;6980.092587963532;6980.51136420466;7009.334113259436;7016.403605693573;7385.741499047507;7595.506292090997;7625.466217364731;7691.769246922601;7774.378049755893;6639.7012134455035;6872.965635171792;6889.483288753263;7012.614306213207;7032.578045317297;5547;6201.441870231701;6274.529410294155;6808.727650894107;6911.75808846688;6229.53278504123;6324.715387807468;6387.361263873576;6394.1687580614225;6413.71138298787;4849.58778503817;4945.378949156952;4957.934044180519;4977.875553111201;5135.127028405254;4935.8025678972435;4956.628252880976;5067.6199285053035;5070.974645126751;5129.897402052013 18:35:28 INFO - PROCESS | 1635 | |4;prototype.html;316.53785473209115;333.17040568983225;347.99478007829873;348.97382696297785;352.3006831439593;4698.718076915387;5061.417936937253;5317.760700768465;5382.165764306534;5458.4814442627985;4196.769177695225;5100.872478188038;5168.508991645787;5250.579953603712;5258.815941442051;3492.266624008961;3795.6583907448326;4130.463039804821;4172.77049762263;4189.832406703744;3629.056445324208;3747.6439738224826;3775.886113596482;4036.4550785643855;4149.543550209474;3435.7251419886347;4004.597241655013;4059.289624315733;4103.589641035903;4148.522919864224;3106.176863131272;3577.803220822867;4088.0990339492278;4090.768293048488;4127.8968025799295;2807.761340286078;3274.705276525124;3610.584782749991;3629.9344733897938;3630.2376500934834;3976.522817261918;4662.7202367858035;4928.605711543068;5024.296598476216;5162.767675454614;3831.3869780834934;4108.712390132692;4356.039782098069;4735.337052812609;4747.501512341189;4706.458512403298;5622.465865742748;5670.291213598301;5674.290713660793;5722.713864306809;3478.339115568034;3828.770273783582;3853.2100919311474;3994.560598334181;4045.59544045596;5585.128981791064;5729.054705973509;5868.887200607778;5887.5584331174905;5890.9410905891245;3519.771214871025;3757.9248415031548;3949.743266687656;4064.8577299794365;4068.1863627274665;3174.380995705815;3486.7210623150086;3649.3066317399607;4149.97925010373;4169.770662613585;4523.457185137804;4693.436787585511;4695.859124226278;4701.435827700698;4717.339572459859;4906.386701662293;5395.487428694346;5609.186667933127;5728.885422291531;5729.742161602738;1055.904968552834;1099.3403957625521;1262.8846681807936;1305.2690493323769;1312.4290933443983;822.847773161967;891.9519564511672;912.9227511536451;934.238595544629;947.3179310896144;4270.053662972601;4419.4973708939115;4778.402699662543;5013.315213318208;5043.823466178739;4671.392718946583;4744.620430365631;4965.553100220997;5623.806715381004;5639.943600563942;4225.915481690349;4541.909161816745;4558.703684260512;4966.428860681068;4995.650304478651;2603.310122817455;2605.1533251692895;2778.9305267368477;2914.4316858212446;2969.317057076841;4760.595349395271;5185.351831021122;5307.911878064988;5830.300363956352;5907.1434641976675;5190.558802501754;5485.917711234335;5869.354371019184;5973;5985.34161242263;5314.601404894648;5570.470805273492;5996.040633498619;6053.849768543962;6114.357992410822;5581.274434323512;5730.255066841284;5759.65442073477;5791.189233507396;5798.59409841307;5720.885582288414;5721.425358588568;5730.255066841284;5765.827025189251;5770.855728606651;5147.790269286789;5379.1662292344745;5425.891482170414;5582.995060889079;6032.638041717511;2964.8221106733663;3103.332783451569;3444.5177675126006;3643.125649843965;3733.4026555750947;5749.338826035059;5751.223584816164;5771.9134212986;5781.335146458043;5804.448577385142;4574.99350142968;5054.69671819692;5365.651232669864;5581.972090139523;5605.943940560543;4446.888827779202;4648.883777905579;5006.274090256965;5621.269234999424;5630.620497978021;5150.40770311419;5213.244079608359;5379.919301210407;5397.8110766123;5404;5403.324584426947;5629.54963602919;5669.971650141888;5690.731951036722;5697.4302569742695;4517.435320584927;4895.4370247422;5550.722463876871;5597.132444471114;5651.704704424585 18:35:28 INFO - PROCESS | 1635 | |5;yui.html;598.9578134046757;736.9594672293028;754.0348354106742;790.0913948958698;800.8798680197967;12090.939545302272;12419.627411177678;14268.072575282613;14874.256287185637;14884.181370024644;13580.456781728719;13726.382312795924;13744.65638359039;13817.723645527109;14015.579532614012;842.9620667069996;853.8975322961238;865.6494119881459;869.6521391443441;870.2341939093589;837.1042984007115;849.8512760266929;866.1944391715701;866.2896424931558;868.2272777228272;910.6129894794719;918.2608000559546;920.7099763574453;921.6543796076471;922.2253307221932;885.6767279942839;911.7355966769629;914.3416739947228;916.4959272400154;918.8208299381623;593.6230493636529;667.4560233409755;674.9088873002131;677.5832862789398;678.3149019490301;11915.523379064827;12345.197562158432;14435.927820360936;14718.632034199123;14850.25748712571;13405.262710550916;13503.325171473602;13556;13618.863811361858;13704.137547649294;11079.667609971713;11331.976368961907;11357.43212839363;11497;11879.406029698566;3517.542719446481;3538.292341531704;3560.1989552350774;3560.537130173087;3655.652712992288;77884;85942;89187;95172;99665.50167249117;10233.691328560004;10306.453862553994;10353.188192066358;10378.584856605652;10411.531481083219;21293;21469.033893474832;21487;21633;21709.240176593743;7312.5246858954;7312.744053958086;7325.670344834495;7477;7532.54804711719;3400.387930172593;4058.1477889642915;4059.9594004060173;4064.817357868682;4097.795110244476;3454.4627640699646;3566.375884220251;3579;3599.892003239907;3608.566971963381;642.2261175283771;642.7364780439997;650.3528988656317;654.5777973207255;655.4887187993373;646.7186773753432;662.456785435938;681.9727210911508;694.989575156373;703.9084918960502;9596.808063838684;9777.41335519871;9942.403455792532;9962.352447090916;9977.750556236007;648.6983552648041;686.7012849410522;691.5781373362245;695.9443244540424;700.8948657701284;4834.806607735652;4838.758062096881;4850.587700045536;4861.416630004422;4871.658983871095;97589;99847;100021.49989250007;100148;100840.49579752202;54757.72621136869;65448;68661.65669171722;72262.55474890473;75507.42895994565;69219.3078069213;71191.64404177947;72654.63672681709;73172;75964.1008974769;7035.206022464239;9416.623335066795;9432;10291.53688084053;10487.423191724529;36254.45618315675;37358;39277.2144557113;46683.066338673714;47621;51143.51557930541;53323.733381332844;61218.693906530185;62010.37989620046;63198.68400657967;10758.354498729788;12888.42002109926;12956.740865182832;13084.738305234032;13199.142055766344;9331.53342332867;11353.829692554453;13897;14456.927715361357;14501.927490362903;9477;10719.517621706886;12599.622011339674;13194.371547824425;13555.59333220005;11316.660500185008;12559.93720031394;13448.663783405493;13456.730865382833;13462.394192261567;12889;12979.156354836905;13035.543755968496;13293.667658308234;13750.243736594182;9702.64163017164;10011.699649010541;10138.442385668859;10286.588536458457;10402.791944160923;8207.548584827891;9963.701088967342;10532.842007369742;10541.631042913461;10552.419616921143 18:35:28 INFO - PROCESS | 1635 | __end_tp_report 18:35:28 INFO - PROCESS | 1635 | __start_cc_report 18:35:28 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,411 18:35:28 INFO - PROCESS | 1635 | __end_cc_report 18:35:28 INFO - PROCESS | 1635 | __startTimestamp1486694128636__endTimestamp 18:35:28 INFO - PROCESS | 1635 | 18:35:28 INFO - PROCESS | 1635 | ------- Summary: start ------- 18:35:28 INFO - PROCESS | 1635 | Number of tests: 6 18:35:28 INFO - PROCESS | 1635 | 18:35:28 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5538.23 Median:5706.23 stddev:639.42 (11.2%) stddev-sans-first:616.60 18:35:28 INFO - PROCESS | 1635 | Values: 3290.8 3314.6 3341.2 3360.8 3367.2 5373.7 5460.4 5468.0 5482.8 5584.8 5349.9 5477.8 5481.4 5486.0 5501.0 4990.1 5019.7 5045.1 5102.8 5116.3 5027.7 5113.9 5114.9 5116.7 5120.1 5106.3 5108.3 5109.4 5111.8 5129.0 4512.9 5100.7 5103.8 5110.9 5127.3 4943.9 5055.7 5063.8 5066.5 5066.5 5373.1 5408.3 5435.0 5472.2 5505.8 5704.9 5815.3 5849.6 5850.1 5887.2 5883.6 5950.0 5978.1 6078.9 6084.1 5681.7 5694.6 5706.2 5707.8 5727.6 6212.3 6252.0 6268.4 6276.3 6285.2 5726.0 5751.3 5826.9 5941.2 5990.8 5110.3 6120.8 6157.9 6171.6 6195.0 4895.3 6006.0 6056.8 6092.8 6094.3 5778.3 5789.1 6125.2 6145.7 6147.5 4851.6 4859.1 4860.9 4890.3 4923.2 3960.5 4204.3 4362.0 4367.9 4374.1 4814.1 5656.5 5671.4 5677.3 5677.7 4682.8 5908.3 5942.1 6002.7 6073.0 4295.4 5441.9 5588.8 5594.9 5635.2 5151.4 5190.2 5223.6 5225.3 5230.3 6225.0 6313.6 6316.9 6321.4 6342.3 6070.0 6150.5 6162.9 6174.0 6218.0 6241.1 6241.9 6268.0 6271.8 6291.7 5559.5 5777.7 5811.4 6081.5 6103.1 6032.6 6079.1 6093.2 6115.8 6123.9 5860.5 5880.9 5884.2 5902.0 5909.1 5667.7 5879.6 5887.3 5900.6 5906.4 4746.4 5392.9 5530.2 5691.2 5802.6 5833.2 5887.8 5898.7 5913.4 5931.6 5725.6 5744.8 5753.4 5757.8 5761.1 18:35:28 INFO - PROCESS | 1635 | 18:35:28 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:63479.47 Median:34067.91 stddev:135956.32 (399.1%) stddev-sans-first:136255.28 18:35:28 INFO - PROCESS | 1635 | Values: 423.6 488.9 490.3 511.3 516.3 38858.7 39754.4 39855.2 39928.4 40050.2 40101.6 40240.2 40585.0 40616.0 40690.2 9125.9 9370.6 9385.0 10404.8 10699.0 10659.5 10792.9 10799.6 10807.4 10823.7 10838.2 10871.0 10872.6 10892.1 10893.2 10758.6 10805.9 10823.2 10824.4 10850.5 10508.9 10601.2 10611.7 10615.2 10650.3 35980.6 36034.9 36166.3 36228.5 36251.1 3554.7 3621.9 3706.7 3708.2 3871.1 23417.0 27089.3 27265.6 27292.9 27716.0 2303.7 2701.0 2889.8 3009.0 3010.3 455225.0 460062.0 461670.0 461671.0 462473.7 46299.5 47250.8 53963.0 54494.0 56935.7 49038.0 50638.0 55567.0 57240.0 57480.0 14441.5 14532.2 14551.9 14593.2 14596.9 8904.5 11103.9 11214.9 11215.9 11338.9 11961.5 16874.8 16972.5 16989.3 17103.6 6254.7 7428.1 7433.3 7440.7 7489.3 788.2 828.1 831.7 836.2 858.9 23846.8 29133.9 29165.7 29316.0 29641.0 990.6 1006.5 1021.7 1029.7 1172.9 15236.0 15252.7 15279.9 15391.4 15485.5 518958.0 522366.0 523298.0 523872.0 525717.0 56589.4 57016.0 57256.4 57362.7 57423.1 1134.8 1311.1 1356.2 1361.6 1372.7 27141.3 28106.3 28279.0 28394.7 29151.3 38760.2 46311.5 49217.3 49375.3 49541.0 537517.0 543527.0 543890.0 544994.0 545204.0 24930.9 25499.0 25522.2 26394.7 36629.6 40288.6 40739.6 41097.8 41480.2 41881.2 27524.3 34133.0 34348.8 35288.1 36845.4 33569.8 33825.3 34684.0 34902.6 35331.8 33744.3 33837.0 33994.5 34002.8 34300.3 27542.9 29246.4 29915.6 30224.7 30279.8 22945.2 23008.4 23071.0 23296.9 23395.8 18:35:28 INFO - PROCESS | 1635 | 18:35:28 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:40150.69 Median:2883.58 stddev:115090.50 (3991.2%) stddev-sans-first:115374.59 18:35:28 INFO - PROCESS | 1635 | Values: 365.4 369.8 377.1 379.0 399.2 18533.1 19106.5 19113.8 19824.2 20019.2 11590.8 11996.9 12002.5 12006.3 12023.9 6268.3 6584.3 6683.9 6688.5 6696.0 6594.8 6722.0 7259.8 7661.2 7684.5 7529.4 7645.0 7770.5 7779.6 7803.4 7030.9 7292.3 7631.2 7773.7 7798.6 7354.4 7629.7 7669.7 7672.8 7677.9 21855.0 27499.0 27683.2 28174.9 28321.3 2746.5 2788.6 2840.5 2860.3 2906.9 16268.5 16415.8 16460.3 16789.9 17251.2 1946.0 2007.1 2020.0 2162.8 2204.0 149807.0 153572.0 155694.2 155931.2 156777.0 20992.9 22945.4 23056.3 23090.4 23288.8 39034.6 39040.8 39117.8 39167.0 39338.4 10275.6 10534.5 10705.5 10722.3 10746.0 8497.0 8509.8 8512.0 8670.3 8681.8 10870.7 10972.6 10977.1 11054.8 11085.4 4070.6 4154.6 4166.7 4182.6 4184.2 933.2 933.6 942.8 972.3 978.1 16818.8 18510.8 18620.2 19806.5 19911.2 969.4 974.1 988.7 1001.8 1004.7 8982.6 10068.6 10258.5 10284.7 10339.7 656815.0 685461.0 693087.0 700072.0 703484.0 126430.0 142642.4 145587.0 148651.0 150385.0 892.2 948.5 972.0 994.1 996.3 13582.7 17490.7 17589.3 18298.6 18481.4 42796.1 51067.5 53874.5 54339.7 56601.4 68650.7 81411.2 82192.6 82259.0 82458.8 5685.9 5950.8 8082.6 8098.4 8205.7 10618.4 11852.2 12042.6 12130.1 12371.4 8820.9 10199.5 10406.8 10825.4 10865.6 6065.7 10305.8 10413.9 10684.8 10788.0 9152.1 10557.6 10772.8 11271.7 11460.3 8017.2 8082.1 8118.1 8165.8 8199.3 9802.6 10408.1 10707.0 10745.7 10798.0 18:35:28 INFO - PROCESS | 1635 | 18:35:28 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5247.36 Median:5215.84 stddev:2870.69 (55.0%) stddev-sans-first:2863.98 18:35:28 INFO - PROCESS | 1635 | Values: 1372.9 1386.5 1387.5 1396.8 1405.4 7864.8 7969.7 8241.8 8294.7 8301.8 8164.0 8204.5 8261.4 8288.5 8303.2 2180.9 2212.5 2212.7 2213.4 2220.0 2171.0 2195.6 2204.2 2214.0 2215.8 2191.6 2195.8 2221.8 2223.2 2224.0 2169.3 2194.1 2195.6 2196.1 2202.3 1651.1 1956.0 2132.7 2221.2 2234.3 8048.2 8219.8 8256.5 8299.0 8319.3 4895.0 4947.5 4986.4 5041.6 5043.6 3238.3 3330.9 3362.9 3388.9 3401.8 1150.6 1171.8 1176.8 1185.9 1219.6 10388.4 10418.3 10435.8 10492.9 10506.0 3689.2 3945.7 4244.2 4255.3 4300.2 8409.9 8449.2 8483.7 8494.6 8506.7 4881.3 4893.8 4899.0 4901.8 4913.5 4346.9 4532.1 4575.3 4584.8 4588.2 3419.1 3429.4 3448.2 3492.0 3507.7 1077.6 1086.2 1086.9 1102.0 1103.4 755.1 786.4 896.6 901.2 926.9 5430.6 5650.9 5690.4 5721.5 5896.2 797.6 909.2 917.7 951.9 975.1 3850.4 3882.8 3899.7 3909.7 3966.2 9660.7 9723.5 9828.8 9884.0 9887.3 9253.2 9746.4 9820.4 9845.3 9850.5 6416.4 7543.7 7732.0 7734.8 7763.1 4831.2 5296.5 5571.0 5637.1 5643.7 8330.0 8375.0 8756.7 8920.3 9010.6 8997.8 9006.8 9028.4 9050.8 9108.9 6861.7 6980.1 6980.5 7009.3 7016.4 7385.7 7595.5 7625.5 7691.8 7774.4 6639.7 6873.0 6889.5 7012.6 7032.6 5547.0 6201.4 6274.5 6808.7 6911.8 6229.5 6324.7 6387.4 6394.2 6413.7 4849.6 4945.4 4957.9 4977.9 5135.1 4935.8 4956.6 5067.6 5071.0 5129.9 18:35:28 INFO - PROCESS | 1635 | 18:35:28 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4453.80 Median:4947.08 stddev:1401.55 (28.3%) stddev-sans-first:1370.65 18:35:28 INFO - PROCESS | 1635 | Values: 316.5 333.2 348.0 349.0 352.3 4698.7 5061.4 5317.8 5382.2 5458.5 4196.8 5100.9 5168.5 5250.6 5258.8 3492.3 3795.7 4130.5 4172.8 4189.8 3629.1 3747.6 3775.9 4036.5 4149.5 3435.7 4004.6 4059.3 4103.6 4148.5 3106.2 3577.8 4088.1 4090.8 4127.9 2807.8 3274.7 3610.6 3629.9 3630.2 3976.5 4662.7 4928.6 5024.3 5162.8 3831.4 4108.7 4356.0 4735.3 4747.5 4706.5 5622.5 5670.3 5674.3 5722.7 3478.3 3828.8 3853.2 3994.6 4045.6 5585.1 5729.1 5868.9 5887.6 5890.9 3519.8 3757.9 3949.7 4064.9 4068.2 3174.4 3486.7 3649.3 4150.0 4169.8 4523.5 4693.4 4695.9 4701.4 4717.3 4906.4 5395.5 5609.2 5728.9 5729.7 1055.9 1099.3 1262.9 1305.3 1312.4 822.8 892.0 912.9 934.2 947.3 4270.1 4419.5 4778.4 5013.3 5043.8 4671.4 4744.6 4965.6 5623.8 5639.9 4225.9 4541.9 4558.7 4966.4 4995.7 2603.3 2605.2 2778.9 2914.4 2969.3 4760.6 5185.4 5307.9 5830.3 5907.1 5190.6 5485.9 5869.4 5973.0 5985.3 5314.6 5570.5 5996.0 6053.8 6114.4 5581.3 5730.3 5759.7 5791.2 5798.6 5720.9 5721.4 5730.3 5765.8 5770.9 5147.8 5379.2 5425.9 5583.0 6032.6 2964.8 3103.3 3444.5 3643.1 3733.4 5749.3 5751.2 5771.9 5781.3 5804.4 4575.0 5054.7 5365.7 5582.0 5605.9 4446.9 4648.9 5006.3 5621.3 5630.6 5150.4 5213.2 5379.9 5397.8 5404.0 5403.3 5629.5 5670.0 5690.7 5697.4 4517.4 4895.4 5550.7 5597.1 5651.7 18:35:28 INFO - PROCESS | 1635 | 18:35:28 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:18315.82 Median:4836.78 stddev:25729.78 (532.0%) stddev-sans-first:25767.57 18:35:28 INFO - PROCESS | 1635 | Values: 599.0 737.0 754.0 790.1 800.9 12090.9 12419.6 14268.1 14874.3 14884.2 13580.5 13726.4 13744.7 13817.7 14015.6 843.0 853.9 865.6 869.7 870.2 837.1 849.9 866.2 866.3 868.2 910.6 918.3 920.7 921.7 922.2 885.7 911.7 914.3 916.5 918.8 593.6 667.5 674.9 677.6 678.3 11915.5 12345.2 14435.9 14718.6 14850.3 13405.3 13503.3 13556.0 13618.9 13704.1 11079.7 11332.0 11357.4 11497.0 11879.4 3517.5 3538.3 3560.2 3560.5 3655.7 77884.0 85942.0 89187.0 95172.0 99665.5 10233.7 10306.5 10353.2 10378.6 10411.5 21293.0 21469.0 21487.0 21633.0 21709.2 7312.5 7312.7 7325.7 7477.0 7532.5 3400.4 4058.1 4060.0 4064.8 4097.8 3454.5 3566.4 3579.0 3599.9 3608.6 642.2 642.7 650.4 654.6 655.5 646.7 662.5 682.0 695.0 703.9 9596.8 9777.4 9942.4 9962.4 9977.8 648.7 686.7 691.6 695.9 700.9 4834.8 4838.8 4850.6 4861.4 4871.7 97589.0 99847.0 100021.5 100148.0 100840.5 54757.7 65448.0 68661.7 72262.6 75507.4 69219.3 71191.6 72654.6 73172.0 75964.1 7035.2 9416.6 9432.0 10291.5 10487.4 36254.5 37358.0 39277.2 46683.1 47621.0 51143.5 53323.7 61218.7 62010.4 63198.7 10758.4 12888.4 12956.7 13084.7 13199.1 9331.5 11353.8 13897.0 14456.9 14501.9 9477.0 10719.5 12599.6 13194.4 13555.6 11316.7 12559.9 13448.7 13456.7 13462.4 12889.0 12979.2 13035.5 13293.7 13750.2 9702.6 10011.7 10138.4 10286.6 10402.8 8207.5 9963.7 10532.8 10541.6 10552.4 18:35:28 INFO - PROCESS | 1635 | -------- Summary: end -------- 18:35:28 INFO - PROCESS | 1635 | 18:35:29 INFO - TEST-INFO | 1635: exit 0 18:35:29 INFO - TEST-OK | dromaeo_css | took 1115772ms 18:35:29 INFO - TEST-START | kraken 18:35:29 INFO - operating with platform_type : mac_ 18:35:29 INFO - Initialising browser for kraken test... 18:35:29 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpFDKcn7/profile http://localhost:49237/getInfo.html) 18:35:31 INFO - PROCESS | 1648 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 18:35:31 INFO - PROCESS | 1648 | 2017-02-09 18:35:31.910 plugin-container[1649:10188] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 18:35:31 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:35:31 INFO - PROCESS | 1648 | 2017-02-09 18:35:31.913 plugin-container[1649:10188] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 18:35:31 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:35:31 INFO - PROCESS | 1648 | 2017-02-09 18:35:31.913 plugin-container[1649:10188] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:35:31 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 18:35:31 INFO - PROCESS | 1648 | colorDepth:24 18:35:31 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 18:35:31 INFO - PROCESS | 1648 | __metrics 18:35:32 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 18:35:32 INFO - TEST-INFO | 1648: exit 0 18:35:32 INFO - Browser initialized. 18:35:32 INFO - Running cycle 1/1 for kraken test... 18:35:32 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/T/tmpFDKcn7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:35:35 INFO - PROCESS | 1650 | 2017-02-09 18:35:35.186 plugin-container[1651:10303] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 18:35:35 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:35:35 INFO - PROCESS | 1650 | 2017-02-09 18:35:35.186 plugin-container[1651:10303] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f17, name = 'com.apple.CFPasteboardClient' 18:35:35 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:35:35 INFO - PROCESS | 1650 | 2017-02-09 18:35:35.187 plugin-container[1651:10303] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:35:44 INFO - PROCESS | 1650 | RSS: Main: 233324544 18:35:44 INFO - PROCESS | 1650 | 18:35:47 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 18:35:48 INFO - PROCESS | 1650 | RSS: Main: 247111680 18:35:48 INFO - PROCESS | 1650 | 18:35:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 18:35:52 INFO - PROCESS | 1650 | RSS: Main: 254898176 18:35:52 INFO - PROCESS | 1650 | 18:35:55 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 18:35:56 INFO - PROCESS | 1650 | RSS: Main: 257560576 18:35:56 INFO - PROCESS | 1650 | 18:35:58 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 18:35:59 INFO - PROCESS | 1650 | RSS: Main: 258781184 18:35:59 INFO - PROCESS | 1650 | 18:36:00 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:36:01 INFO - PROCESS | 1650 | RSS: Main: 259141632 18:36:01 INFO - PROCESS | 1650 | 18:36:04 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 18:36:05 INFO - PROCESS | 1650 | RSS: Main: 264634368 18:36:05 INFO - PROCESS | 1650 | 18:36:08 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 18:36:08 INFO - PROCESS | 1650 | RSS: Main: 264298496 18:36:08 INFO - PROCESS | 1650 | 18:36:11 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 18:36:12 INFO - PROCESS | 1650 | RSS: Main: 265383936 18:36:12 INFO - PROCESS | 1650 | 18:36:14 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:36:14 INFO - PROCESS | 1650 | RSS: Main: 264773632 18:36:14 INFO - PROCESS | 1650 | 18:36:16 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:36:17 INFO - PROCESS | 1650 | RSS: Main: 264773632 18:36:17 INFO - PROCESS | 1650 | 18:36:19 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:36:20 INFO - PROCESS | 1650 | RSS: Main: 264871936 18:36:20 INFO - PROCESS | 1650 | 18:36:23 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:36:24 INFO - PROCESS | 1650 | RSS: Main: 265187328 18:36:24 INFO - PROCESS | 1650 | 18:36:27 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:36:28 INFO - PROCESS | 1650 | RSS: Main: 262791168 18:36:28 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 18:36:30 INFO - PROCESS | 1650 | RSS: Main: 265834496 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | __start_tp_report 18:36:30 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 18:36:30 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 18:36:30 INFO - PROCESS | 1650 | |i|pagename|runs| 18:36:30 INFO - PROCESS | 1650 | |0;ai-astar;105;118;119;118;121;119;118;118;119;120 18:36:30 INFO - PROCESS | 1650 | |1;audio-beat-detection;175;148;105;149;103;109;147;146;132;117 18:36:30 INFO - PROCESS | 1650 | |2;audio-dft;206;206;209;219;259;274;196;194;192;288 18:36:30 INFO - PROCESS | 1650 | |3;audio-fft;86;76;74;109;87;73;59;62;73;65 18:36:30 INFO - PROCESS | 1650 | |4;audio-oscillator;91;71;85;72;69;66;83;72;72;82 18:36:30 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;92;94;91;92;93;91;93;95;92;92 18:36:30 INFO - PROCESS | 1650 | |6;imaging-darkroom;98;100;99;128;154;98;98;99;99;99 18:36:30 INFO - PROCESS | 1650 | |7;imaging-desaturate;148;148;91;96;92;94;94;90;92;92 18:36:30 INFO - PROCESS | 1650 | |8;json-parse-financial;64;64;68;100;95;54;63;96;97;98 18:36:30 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;69;79;88;113;67;95;103;100;81;62 18:36:30 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;90;89;91;107;110;110;90;90;89;93 18:36:30 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;154;160;128;147;171;159;151;143;208;141 18:36:30 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;195;220;213;235;189;218;189;207;238;242 18:36:30 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;67;67;70;67;70;70;71;83;94;90 18:36:30 INFO - PROCESS | 1650 | __end_tp_report 18:36:30 INFO - PROCESS | 1650 | __start_cc_report 18:36:30 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,986 18:36:30 INFO - PROCESS | 1650 | __end_cc_report 18:36:30 INFO - PROCESS | 1650 | __startTimestamp1486694190476__endTimestamp 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | ------- Summary: start ------- 18:36:30 INFO - PROCESS | 1650 | Number of tests: 14 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:117.50 Median:119.00 stddev:4.50 (3.8%) stddev-sans-first:1.05 18:36:30 INFO - PROCESS | 1650 | Values: 105.0 118.0 119.0 118.0 121.0 119.0 118.0 118.0 119.0 120.0 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:133.10 Median:146.50 stddev:23.87 (16.3%) stddev-sans-first:19.93 18:36:30 INFO - PROCESS | 1650 | Values: 175.0 148.0 105.0 149.0 103.0 109.0 147.0 146.0 132.0 117.0 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:224.30 Median:214.00 stddev:35.63 (16.6%) stddev-sans-first:37.17 18:36:30 INFO - PROCESS | 1650 | Values: 206.0 206.0 209.0 219.0 259.0 274.0 196.0 194.0 192.0 288.0 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:76.40 Median:73.50 stddev:14.67 (20.0%) stddev-sans-first:15.14 18:36:30 INFO - PROCESS | 1650 | Values: 86.0 76.0 74.0 109.0 87.0 73.0 59.0 62.0 73.0 65.0 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:77.00 stddev:8.25 (10.7%) stddev-sans-first:6.82 18:36:30 INFO - PROCESS | 1650 | Values: 91.0 71.0 85.0 72.0 69.0 66.0 83.0 72.0 72.0 82.0 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:92.50 Median:92.50 stddev:1.27 (1.4%) stddev-sans-first:1.33 18:36:30 INFO - PROCESS | 1650 | Values: 92.0 94.0 91.0 92.0 93.0 91.0 93.0 95.0 92.0 92.0 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:107.20 Median:98.50 stddev:18.85 (19.1%) stddev-sans-first:19.70 18:36:30 INFO - PROCESS | 1650 | Values: 98.0 100.0 99.0 128.0 154.0 98.0 98.0 99.0 99.0 99.0 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:103.70 Median:92.00 stddev:23.41 (25.4%) stddev-sans-first:18.55 18:36:30 INFO - PROCESS | 1650 | Values: 148.0 148.0 91.0 96.0 92.0 94.0 94.0 90.0 92.0 92.0 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:79.90 Median:81.50 stddev:18.60 (22.8%) stddev-sans-first:18.82 18:36:30 INFO - PROCESS | 1650 | Values: 64.0 64.0 68.0 100.0 95.0 54.0 63.0 96.0 97.0 98.0 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:85.70 Median:74.00 stddev:16.99 (23.0%) stddev-sans-first:16.91 18:36:30 INFO - PROCESS | 1650 | Values: 69.0 79.0 88.0 113.0 67.0 95.0 103.0 100.0 81.0 62.0 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:95.90 Median:90.00 stddev:9.15 (10.2%) stddev-sans-first:9.45 18:36:30 INFO - PROCESS | 1650 | Values: 90.0 89.0 91.0 107.0 110.0 110.0 90.0 90.0 89.0 93.0 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:156.20 Median:156.50 stddev:21.71 (13.9%) stddev-sans-first:23.01 18:36:30 INFO - PROCESS | 1650 | Values: 154.0 160.0 128.0 147.0 171.0 159.0 151.0 143.0 208.0 141.0 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:214.60 Median:219.00 stddev:19.75 (9.0%) stddev-sans-first:19.63 18:36:30 INFO - PROCESS | 1650 | Values: 195.0 220.0 213.0 235.0 189.0 218.0 189.0 207.0 238.0 242.0 18:36:30 INFO - PROCESS | 1650 | 18:36:30 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.90 Median:70.50 stddev:10.18 (14.4%) stddev-sans-first:10.39 18:36:30 INFO - PROCESS | 1650 | Values: 67.0 67.0 70.0 67.0 70.0 70.0 71.0 83.0 94.0 90.0 18:36:30 INFO - PROCESS | 1650 | -------- Summary: end -------- 18:36:30 INFO - PROCESS | 1650 | 18:36:31 INFO - TEST-INFO | 1650: exit 0 18:36:31 INFO - TEST-OK | kraken | took 62093ms 18:36:31 INFO - SUITE-END | took 1177s 18:36:31 INFO - Completed test suite (00:19:38) 18:36:31 INFO - javascript benchmark 18:36:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7181.531059502751, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3290.8354582270895, 3314.5691060162176, 3341.198112453012, 3360.781549199302, 3367.191873950251, 5373.704446255454, 5460.426655201206, 5468.015757163714, 5482.835514934548, 5584.776608935648, 5349.946500534994, 5477.75350109245, 5481.369642491105, 5486.039943009967, 5500.972495137528, 4990.126727822635, 5019.749012549376, 5045.142325804603, 5102.846914592568, 5116.283720279164, 5027.673201241926, 5113.872153196181, 5114.872128196787, 5116.692998420088, 5120.129577971735, 5106.31064806252, 5108.259302401149, 5109.386873575158, 5111.7699703513435, 5129.025485157827, 4512.9097418051615, 5100.668456550312, 5103.846884593431, 5110.872228194306, 5127.333446651949, 4943.876403089916, 5055.69665820052, 5063.797448102072, 5066.493350664941, 5066.544011039025, 5373.1402975524115, 5408.323959505062, 5435.0488664483955, 5472.179173124024, 5505.752241149158, 5704.942950570483, 5815.302163740336, 5849.590528662995, 5850.0932355484965, 5887.205227294303, 5883.588148829584, 5950.0, 5978.133170690269, 6078.8784224315705, 6084.087386891957, 5681.715914204273, 5694.572907031948, 5706.229658996024, 5707.77168913243, 5727.570432217601, 6212.285587157445, 6252.0, 6268.373162683786, 6276.278228003746, 6285.182926219653, 5725.971370143206, 5751.309842818889, 5826.883462330729, 5941.197938278364, 5990.7903223386975, 5110.335656364723, 6120.816375508742, 6157.907631385533, 6171.6297022178815, 6195.0, 4895.290182923527, 6005.969970150209, 6056.818295451144, 6092.786752463642, 6094.329623741473, 5778.306603207642, 5789.060664677296, 6125.234345706786, 6145.692715364214, 6147.477464415575, 4851.611871050308, 4859.101066302675, 4860.854174374775, 4890.290907818347, 4923.236898280772, 3960.5049368828895, 4204.327307630764, 4361.978190109029, 4367.912641747147, 4374.081442896963, 4814.061258054716, 5656.547476201894, 5671.376148623648, 5677.261955945701, 5677.659340439504, 4682.789274482657, 5908.320543137508, 5942.078977758454, 6002.729877155539, 6073.0, 4295.441592593005, 5441.863953401117, 5588.832335029955, 5594.916076258859, 5635.154726791015, 5151.4333423323405, 5190.2214667800135, 5223.634345595848, 5225.3207083079715, 5230.293910322058, 6225.0, 6313.589616674902, 6316.873662526632, 6321.3564473236365, 6342.302346741947, 6069.969650151721, 6150.538709596887, 6162.907556386568, 6174.043023331391, 6218.0, 6241.095041218907, 6241.906371404341, 6268.0, 6271.78048768291, 6291.748330066746, 5559.499645031805, 5777.740001700017, 5811.418858114154, 6081.543884208613, 6103.084537319438, 6032.552187475062, 6079.118527813531, 6093.238345206849, 6115.788007244199, 6123.938760612336, 5860.472557469848, 5880.882382352414, 5884.176215329771, 5902.026165682614, 5909.143174239625, 5667.716614169192, 5879.559033072451, 5887.293524777054, 5900.557458190567, 5906.350301466921, 4746.382970213988, 5392.919106213331, 5530.225768392509, 5691.23168372281, 5802.564807639528, 5833.212516310244, 5887.793927212527, 5898.705064746831, 5913.438223368773, 5931.6441013539325, 5725.5992080555625, 5744.798932037359, 5753.3958934311295, 5757.769689212385, 5761.107028410603], "value": 5499.90209624504, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.62297555175894, 488.85334399680085, 490.31356101457976, 511.3224976905599, 516.3158814570695, 38858.697010754084, 39754.403683944765, 39855.2028959421, 39928.401073983914, 40050.19899602006, 40101.598984010154, 40240.19519609606, 40585.0, 40616.0, 40690.18619627606, 9125.863112053341, 9370.625174992992, 9385.0, 10404.843927341097, 10698.983596558315, 10659.520321585509, 10792.946035269813, 10799.622013229538, 10807.4055926924, 10823.729406764855, 10838.241323107386, 10871.0, 10872.565097396095, 10892.074173695246, 10893.183011274186, 10758.623448179276, 10805.891941080647, 10823.188260880386, 10824.350538967648, 10850.457477126112, 10508.94745526275, 10601.204909631731, 10611.734706632396, 10615.203859710475, 10650.25448218625, 35980.64019359825, 36034.91895243147, 36166.27667446663, 36228.45657315142, 36251.09372265688, 3554.680078792921, 3621.94567081494, 3706.666400023984, 3708.165662725892, 3871.0709429736944, 23417.0, 27089.32276693079, 27265.591016134775, 27292.863535682394, 27716.0, 2303.689001984744, 2701.0411303987, 2889.754370878478, 3009.0, 3010.277533391992, 455225.0, 460062.0, 461670.0, 461671.0, 462473.68763156305, 46299.5370046302, 47250.76374618105, 53963.0, 54494.0, 56935.71532142313, 49038.0, 50637.98724025572, 55567.0, 57240.0, 57480.0, 14441.49454769078, 14532.200728959797, 14551.927240363942, 14593.197374144524, 14596.854031459761, 8904.465732056098, 11103.944480277547, 11214.943925280322, 11215.94392028051, 11338.866113388594, 11961.46173422198, 16874.831251687327, 16972.49082527526, 16989.320427183022, 17103.572410689583, 6254.65450456079, 7428.068702137375, 7433.331000210007, 7440.73957411488, 7489.3412838442655, 788.1960400391569, 828.1470085811624, 831.6631764135535, 836.2473773603805, 858.9098144694717, 23846.7615323848, 29133.85433072821, 29165.708342916725, 29316.0, 29640.962566310507, 990.6136606723384, 1006.5068116622803, 1021.6781713760141, 1029.7219750667277, 1172.9178957472895, 15236.009659372105, 15252.694946101015, 15279.923600381928, 15391.384344626089, 15485.535433937, 518958.0, 522366.0, 523298.0, 523872.0, 525717.0, 56589.43410565924, 57016.0, 57256.42743572594, 57362.7131864338, 57423.13865292024, 1134.7843909657304, 1311.0560396514684, 1356.1998420931698, 1361.5711051019096, 1372.6774208061097, 27141.321466963484, 28106.2973425666, 28279.01023464169, 28394.71605283921, 29151.271218219714, 38760.22479550337, 46311.53688463207, 49217.2617410732, 49375.25937110875, 49541.0, 537517.0, 543527.0, 543890.0, 544994.0, 545204.0, 24930.875345623157, 25499.0, 25522.23433297004, 26394.73605263923, 36629.63370366262, 40288.59711402848, 40739.59260407358, 41097.794511027256, 41480.1703965913, 41881.1623767529, 27524.311892202855, 34133.0, 34348.82825585856, 35288.11779705528, 36845.44731828971, 33569.83215083909, 33825.323493530486, 34684.0, 34902.60389584388, 35331.82334088313, 33744.32511349808, 33837.0, 33994.490082649274, 34002.829985849916, 34300.31399371949, 27542.862285688443, 29246.415071698022, 29915.55126673145, 30224.69775302307, 30279.848600756854, 22945.217456471368, 23008.4247893804, 23071.0, 23296.883515582314, 23395.76604234004], "value": 18810.04648024024, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.4116871836341, 369.79291596705843, 377.10813925067214, 378.98484060637577, 399.15578551363876, 18533.07334633268, 19106.522336941583, 19113.808861911377, 19824.20703171875, 20019.199232030736, 11590.768184636303, 11996.940015299911, 12002.51989920402, 12006.27962322259, 12023.939880300586, 6268.341824108471, 6584.341565843425, 6683.866322673543, 6688.461653819624, 6696.0, 6594.769183078593, 6722.0, 7259.782206533812, 7661.157272699998, 7684.462087653867, 7529.360004399633, 7645.0, 7770.494917830351, 7779.611019449033, 7803.375729941619, 7030.859382812315, 7292.307230813064, 7631.236876312352, 7773.7279195228175, 7798.649060792278, 7354.44841636874, 7629.656665450068, 7669.654865531064, 7672.808179795494, 7677.923220767777, 21855.0, 27499.0, 27683.169504914884, 28174.859125704446, 28321.291967700767, 2746.4644394343113, 2788.60959465675, 2840.4603125406106, 2860.256333353343, 2906.89825856094, 16268.51194464168, 16415.753763693556, 16460.341586336534, 16789.90865593745, 17251.223694933757, 1945.9708104378446, 2007.1268997985776, 2019.9899000504904, 2162.770097439106, 2203.986166363459, 149807.0, 153572.0, 155694.22152889275, 155931.2203438987, 156777.0, 20992.895035524725, 22945.37838229611, 23056.308310750705, 23090.422739431313, 23288.808014557202, 39034.609653903666, 39040.80479597641, 39117.80441097776, 39167.02082447905, 39338.40992385116, 10275.588976441008, 10534.473276336152, 10705.518251678694, 10722.303050301705, 10746.0, 8497.0, 8509.829803403898, 8511.957440212884, 8670.26302764272, 8681.782955426039, 10870.673879783619, 10972.561097556169, 10977.066949309397, 11054.778904421866, 11085.445727713583, 4070.61329173728, 4154.6399461601495, 4166.7082904608305, 4182.623563879205, 4184.184084103571, 933.2487347685043, 933.6265493802533, 942.820864035831, 972.2659392158874, 978.1441238915949, 16818.831811681972, 18510.81489185118, 18620.16209270586, 19806.50483737918, 19911.203551857765, 969.3941286695815, 974.0843607009389, 988.7379844341256, 1001.7996400719885, 1004.7186787699455, 8982.595783189772, 10068.647597334058, 10258.538365773558, 10284.742881427874, 10339.741506462247, 656815.0, 685461.0, 693087.0, 700072.0, 703484.0, 126430.0, 142642.43393915027, 145587.0, 148651.0, 150385.0, 892.1569117184199, 948.4878165790534, 971.9854202186973, 994.1152374386946, 996.3274789517047, 13582.660433489242, 17490.73763893568, 17589.296428143243, 18298.634027319644, 18481.445556633324, 42796.14407711891, 51067.46797595929, 53874.461255386945, 54339.72830135824, 56601.43398565962, 68650.65674671595, 81411.15060972837, 82192.58903705444, 82259.0, 82458.76311855447, 5685.886282274414, 5950.821475355746, 8082.555459449787, 8098.433109682265, 8205.671773129247, 10618.415987120783, 11852.229605075643, 12042.63872083839, 12130.090243231616, 12371.381430928597, 8820.911790882266, 10199.490025498844, 10406.843897341698, 10825.350478971286, 10865.565377384815, 6065.6663883486835, 10305.793884122426, 10413.9479302603, 10684.83972740425, 10788.02907738276, 9152.130547598234, 10557.63048293306, 10772.838407424051, 11271.718207044889, 11460.255083419552, 8017.158198389086, 8082.110967793656, 8118.147594502492, 8165.79585510367, 8199.262066414052, 9802.607895684092, 10408.063274305348, 10707.0, 10745.731356716145, 10798.0], "value": 10920.634956522006, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1372.9107608005486, 1386.5493714542763, 1387.4658256571217, 1396.8114304568883, 1405.4378248700518, 7864.803379915505, 7969.681212751498, 8241.793955151124, 8294.668213271461, 8301.792455188623, 8163.959180204106, 8204.548749818758, 8261.421700480954, 8288.46125001876, 8303.211194936472, 2180.890955452225, 2212.4911270407815, 2212.7012853264846, 2213.4245096275004, 2220.0342850859847, 2170.9891450542805, 2195.5938151441956, 2204.151401710337, 2213.966790498144, 2215.8116560092412, 2191.638379667353, 2195.7913998170147, 2221.8111460525793, 2223.1552010236087, 2223.977760222393, 2169.327508472371, 2194.0784870354328, 2195.5938151442033, 2196.121551379461, 2202.262242148874, 1651.1414064686417, 1956.0341856434193, 2132.7334083239593, 2221.244776775904, 2234.340869443518, 8048.195180481964, 8219.753407397788, 8256.545889976049, 8299.0, 8319.292860106898, 4894.975525122387, 4947.455779864248, 4986.351774269358, 5041.647084704072, 5043.571296439807, 3238.3199528098894, 3330.9000729977897, 3362.865485380582, 3388.8644454221803, 3401.795892246473, 1150.6433005768238, 1171.794935886225, 1176.7999440095205, 1185.851768528934, 1219.6402061391912, 10388.428636424993, 10418.322809017389, 10435.791284174275, 10492.895071049268, 10505.999999999924, 3689.206820533598, 3945.743526670757, 4244.236037513216, 4255.29787585045, 4300.182965236658, 8409.873851892227, 8449.155084491624, 8483.74548763538, 8494.575271236414, 8506.744797655956, 4881.316615673809, 4893.804247830047, 4898.975505122452, 4901.803927842846, 4913.459519452857, 4346.869593912188, 4532.070925460339, 4575.336576196433, 4584.816607335669, 4588.197065513522, 3419.111031131874, 3429.4341433663667, 3448.2241495663275, 3492.0, 3507.6667716566885, 1077.6497638267595, 1086.163653986426, 1086.875009373932, 1101.9724506887392, 1103.4441437572984, 755.0825746717765, 786.4494853602387, 896.6368620708623, 901.2474583722534, 926.9351145419756, 5430.5927055471, 5650.886982260414, 5690.430956904359, 5721.5136713379, 5896.204012458349, 797.5533701127272, 909.2226146644524, 917.6604656277086, 951.9286053546011, 975.0834215837232, 3850.383938569815, 3882.8446862125197, 3899.707521935866, 3909.7458665186673, 3966.2464131814863, 9660.71017869465, 9723.513824308757, 9828.803423931482, 9883.950580247196, 9887.258455615858, 9253.167214950687, 9746.415215087118, 9820.361676491006, 9845.261605379625, 9850.50307140969, 6416.422521973045, 7543.698252069856, 7732.0, 7734.7679569613, 7763.107242667165, 4831.227003679394, 5296.549793267462, 5571.0, 5637.126245431965, 5643.746031428514, 8330.0, 8374.958125209336, 8756.693515726918, 8920.286377089948, 9010.594523246338, 8997.82004359896, 9006.774830629025, 9028.36801423894, 9050.818983620422, 9108.863367049367, 6861.656917154222, 6980.092587963532, 6980.51136420466, 7009.334113259436, 7016.403605693573, 7385.741499047507, 7595.506292090997, 7625.466217364731, 7691.769246922601, 7774.378049755893, 6639.7012134455035, 6872.965635171792, 6889.483288753263, 7012.614306213207, 7032.578045317297, 5547.0, 6201.441870231701, 6274.529410294155, 6808.727650894107, 6911.75808846688, 6229.53278504123, 6324.715387807468, 6387.361263873576, 6394.1687580614225, 6413.71138298787, 4849.58778503817, 4945.378949156952, 4957.934044180519, 4977.875553111201, 5135.127028405254, 4935.8025678972435, 4956.628252880976, 5067.6199285053035, 5070.974645126751, 5129.897402052013], "value": 4249.682900944683, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.53785473209115, 333.17040568983225, 347.99478007829873, 348.97382696297785, 352.3006831439593, 4698.718076915387, 5061.417936937253, 5317.760700768465, 5382.165764306534, 5458.4814442627985, 4196.769177695225, 5100.872478188038, 5168.508991645787, 5250.579953603712, 5258.815941442051, 3492.266624008961, 3795.6583907448326, 4130.463039804821, 4172.77049762263, 4189.832406703744, 3629.056445324208, 3747.6439738224826, 3775.886113596482, 4036.4550785643855, 4149.543550209474, 3435.7251419886347, 4004.597241655013, 4059.289624315733, 4103.589641035903, 4148.522919864224, 3106.176863131272, 3577.803220822867, 4088.0990339492278, 4090.768293048488, 4127.8968025799295, 2807.761340286078, 3274.705276525124, 3610.584782749991, 3629.9344733897938, 3630.2376500934834, 3976.522817261918, 4662.7202367858035, 4928.605711543068, 5024.296598476216, 5162.767675454614, 3831.3869780834934, 4108.712390132692, 4356.039782098069, 4735.337052812609, 4747.501512341189, 4706.458512403298, 5622.465865742748, 5670.291213598301, 5674.290713660793, 5722.713864306809, 3478.339115568034, 3828.770273783582, 3853.2100919311474, 3994.560598334181, 4045.59544045596, 5585.128981791064, 5729.054705973509, 5868.887200607778, 5887.5584331174905, 5890.9410905891245, 3519.771214871025, 3757.9248415031548, 3949.743266687656, 4064.8577299794365, 4068.1863627274665, 3174.380995705815, 3486.7210623150086, 3649.3066317399607, 4149.97925010373, 4169.770662613585, 4523.457185137804, 4693.436787585511, 4695.859124226278, 4701.435827700698, 4717.339572459859, 4906.386701662293, 5395.487428694346, 5609.186667933127, 5728.885422291531, 5729.742161602738, 1055.904968552834, 1099.3403957625521, 1262.8846681807936, 1305.2690493323769, 1312.4290933443983, 822.847773161967, 891.9519564511672, 912.9227511536451, 934.238595544629, 947.3179310896144, 4270.053662972601, 4419.4973708939115, 4778.402699662543, 5013.315213318208, 5043.823466178739, 4671.392718946583, 4744.620430365631, 4965.553100220997, 5623.806715381004, 5639.943600563942, 4225.915481690349, 4541.909161816745, 4558.703684260512, 4966.428860681068, 4995.650304478651, 2603.310122817455, 2605.1533251692895, 2778.9305267368477, 2914.4316858212446, 2969.317057076841, 4760.595349395271, 5185.351831021122, 5307.911878064988, 5830.300363956352, 5907.1434641976675, 5190.558802501754, 5485.917711234335, 5869.354371019184, 5973.0, 5985.34161242263, 5314.601404894648, 5570.470805273492, 5996.040633498619, 6053.849768543962, 6114.357992410822, 5581.274434323512, 5730.255066841284, 5759.65442073477, 5791.189233507396, 5798.59409841307, 5720.885582288414, 5721.425358588568, 5730.255066841284, 5765.827025189251, 5770.855728606651, 5147.790269286789, 5379.1662292344745, 5425.891482170414, 5582.995060889079, 6032.638041717511, 2964.8221106733663, 3103.332783451569, 3444.5177675126006, 3643.125649843965, 3733.4026555750947, 5749.338826035059, 5751.223584816164, 5771.9134212986, 5781.335146458043, 5804.448577385142, 4574.99350142968, 5054.69671819692, 5365.651232669864, 5581.972090139523, 5605.943940560543, 4446.888827779202, 4648.883777905579, 5006.274090256965, 5621.269234999424, 5630.620497978021, 5150.40770311419, 5213.244079608359, 5379.919301210407, 5397.8110766123, 5404.0, 5403.324584426947, 5629.54963602919, 5669.971650141888, 5690.731951036722, 5697.4302569742695, 4517.435320584927, 4895.4370247422, 5550.722463876871, 5597.132444471114, 5651.704704424585], "value": 4020.8408234979124, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.9578134046757, 736.9594672293028, 754.0348354106742, 790.0913948958698, 800.8798680197967, 12090.939545302272, 12419.627411177678, 14268.072575282613, 14874.256287185637, 14884.181370024644, 13580.456781728719, 13726.382312795924, 13744.65638359039, 13817.723645527109, 14015.579532614012, 842.9620667069996, 853.8975322961238, 865.6494119881459, 869.6521391443441, 870.2341939093589, 837.1042984007115, 849.8512760266929, 866.1944391715701, 866.2896424931558, 868.2272777228272, 910.6129894794719, 918.2608000559546, 920.7099763574453, 921.6543796076471, 922.2253307221932, 885.6767279942839, 911.7355966769629, 914.3416739947228, 916.4959272400154, 918.8208299381623, 593.6230493636529, 667.4560233409755, 674.9088873002131, 677.5832862789398, 678.3149019490301, 11915.523379064827, 12345.197562158432, 14435.927820360936, 14718.632034199123, 14850.25748712571, 13405.262710550916, 13503.325171473602, 13556.0, 13618.863811361858, 13704.137547649294, 11079.667609971713, 11331.976368961907, 11357.43212839363, 11497.0, 11879.406029698566, 3517.542719446481, 3538.292341531704, 3560.1989552350774, 3560.537130173087, 3655.652712992288, 77884.0, 85942.0, 89187.0, 95172.0, 99665.50167249117, 10233.691328560004, 10306.453862553994, 10353.188192066358, 10378.584856605652, 10411.531481083219, 21293.0, 21469.033893474832, 21487.0, 21633.0, 21709.240176593743, 7312.5246858954, 7312.744053958086, 7325.670344834495, 7477.0, 7532.54804711719, 3400.387930172593, 4058.1477889642915, 4059.9594004060173, 4064.817357868682, 4097.795110244476, 3454.4627640699646, 3566.375884220251, 3579.0, 3599.892003239907, 3608.566971963381, 642.2261175283771, 642.7364780439997, 650.3528988656317, 654.5777973207255, 655.4887187993373, 646.7186773753432, 662.456785435938, 681.9727210911508, 694.989575156373, 703.9084918960502, 9596.808063838684, 9777.41335519871, 9942.403455792532, 9962.352447090916, 9977.750556236007, 648.6983552648041, 686.7012849410522, 691.5781373362245, 695.9443244540424, 700.8948657701284, 4834.806607735652, 4838.758062096881, 4850.587700045536, 4861.416630004422, 4871.658983871095, 97589.0, 99847.0, 100021.49989250007, 100148.0, 100840.49579752202, 54757.72621136869, 65448.0, 68661.65669171722, 72262.55474890473, 75507.42895994565, 69219.3078069213, 71191.64404177947, 72654.63672681709, 73172.0, 75964.1008974769, 7035.206022464239, 9416.623335066795, 9432.0, 10291.53688084053, 10487.423191724529, 36254.45618315675, 37358.0, 39277.2144557113, 46683.066338673714, 47621.0, 51143.51557930541, 53323.733381332844, 61218.693906530185, 62010.37989620046, 63198.68400657967, 10758.354498729788, 12888.42002109926, 12956.740865182832, 13084.738305234032, 13199.142055766344, 9331.53342332867, 11353.829692554453, 13897.0, 14456.927715361357, 14501.927490362903, 9477.0, 10719.517621706886, 12599.622011339674, 13194.371547824425, 13555.59333220005, 11316.660500185008, 12559.93720031394, 13448.663783405493, 13456.730865382833, 13462.394192261567, 12889.0, 12979.156354836905, 13035.543755968496, 13293.667658308234, 13750.243736594182, 9702.64163017164, 10011.699649010541, 10138.442385668859, 10286.588536458457, 10402.791944160923, 8207.548584827891, 9963.701088967342, 10532.842007369742, 10541.631042913461, 10552.419616921143], "value": 7105.356020463244, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1638.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 119.0, 118.0, 121.0, 119.0, 118.0, 118.0, 119.0, 120.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 148.0, 105.0, 149.0, 103.0, 109.0, 147.0, 146.0, 132.0, 117.0], "value": 133.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 206.0, 209.0, 219.0, 259.0, 274.0, 196.0, 194.0, 192.0, 288.0], "value": 224.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 76.0, 74.0, 109.0, 87.0, 73.0, 59.0, 62.0, 73.0, 65.0], "value": 76.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 85.0, 72.0, 69.0, 66.0, 83.0, 72.0, 72.0, 82.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 91.0, 92.0, 93.0, 91.0, 93.0, 95.0, 92.0, 92.0], "value": 92.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 100.0, 99.0, 128.0, 154.0, 98.0, 98.0, 99.0, 99.0, 99.0], "value": 107.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 91.0, 96.0, 92.0, 94.0, 94.0, 90.0, 92.0, 92.0], "value": 103.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 68.0, 100.0, 95.0, 54.0, 63.0, 96.0, 97.0, 98.0], "value": 79.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 88.0, 113.0, 67.0, 95.0, 103.0, 100.0, 81.0, 62.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 91.0, 107.0, 110.0, 110.0, 90.0, 90.0, 89.0, 93.0], "value": 95.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 160.0, 128.0, 147.0, 171.0, 159.0, 151.0, 143.0, 208.0, 141.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 220.0, 213.0, 235.0, 189.0, 218.0, 189.0, 207.0, 238.0, 242.0], "value": 214.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 70.0, 67.0, 70.0, 70.0, 71.0, 83.0, 94.0, 90.0], "value": 74.9, "unit": "score"}]}]} 18:36:31 INFO - Return code: 0 18:36:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:36:31 ERROR - # TBPL SUCCESS # 18:36:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:36:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:36:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:36:31 INFO - 2017-02-09 18:36:31.784 screenresolution[1655:10688] starting screenresolution argv=screenresolution get 18:36:31 INFO - 2017-02-09 18:36:31.787 screenresolution[1655:10688] Display 0: 1600x1200x32@60 18:36:31 INFO - 2017-02-09 18:36:31.793 screenresolution[1656:10691] starting screenresolution argv=screenresolution list 18:36:31 INFO - Available Modes on Display 0 18:36:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:36:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:36:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:36:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:36:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:36:32 INFO - Intel Iris: 18:36:32 INFO - Chipset Model: Intel Iris 18:36:32 INFO - Type: GPU 18:36:32 INFO - Bus: Built-In 18:36:32 INFO - VRAM (Dynamic, Max): 1536 MB 18:36:32 INFO - Vendor: Intel (0x8086) 18:36:32 INFO - Device ID: 0x0a2e 18:36:32 INFO - Revision ID: 0x0009 18:36:32 INFO - Displays: 18:36:32 INFO - Display: 18:36:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:36:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:36:32 INFO - Display Serial Number: 1600x1200 60 18:36:32 INFO - Main Display: Yes 18:36:32 INFO - Mirror: Off 18:36:32 INFO - Online: Yes 18:36:32 INFO - Rotation: Supported 18:36:32 INFO - Return code: 0 18:36:32 INFO - Running post-action listener: _resource_record_post_action 18:36:32 INFO - [mozharness: 2017-02-10 02:36:32.027041Z] Finished run-tests step (success) 18:36:32 INFO - Running post-run listener: _resource_record_post_run 18:36:32 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 38935040; Write bytes: 558183936; Read time: 247; Write time: 1725 18:36:32 INFO - TinderboxPrint: CPU usage
54.4% 18:36:32 INFO - TinderboxPrint: I/O read bytes / time
38,935,040 / 247 18:36:32 INFO - TinderboxPrint: I/O write bytes / time
558,183,936 / 1,725 18:36:32 INFO - TinderboxPrint: CPU idle
2,190.2 (45.6%) 18:36:32 INFO - TinderboxPrint: CPU system
967.5 (20.1%) 18:36:32 INFO - TinderboxPrint: CPU user
1,648.0 (34.3%) 18:36:32 INFO - TinderboxPrint: Swap in / out
291,172,352 / 0 18:36:32 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 236120576; Write bytes: 230073856; Read time: 17634; Write time: 509 18:36:32 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 37972480; Write bytes: 303345664; Read time: 234; Write time: 1157 18:36:32 INFO - Running post-run listener: _upload_blobber_files 18:36:32 INFO - Blob upload gear active. 18:36:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:36:32 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 18:36:32 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'] 18:36:32 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 18:36:32 INFO - (blobuploader) - INFO - Open directory for files ... 18:36:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:36:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:36:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:34 INFO - (blobuploader) - INFO - Done attempting. 18:36:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:36:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:36:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:34 INFO - (blobuploader) - INFO - Done attempting. 18:36:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:36:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:36:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:36 INFO - (blobuploader) - INFO - Done attempting. 18:36:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:36:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:36:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:38 INFO - (blobuploader) - INFO - Done attempting. 18:36:38 INFO - (blobuploader) - INFO - Iteration through files over. 18:36:38 INFO - Return code: 0 18:36:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:36:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:36:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c73b414d969041072580b57187558a2b01177bba988fed12264692975181ecf078801aa3e6a1d9ed393895690c6ffbbde3be1247140d6c6dec8f676c320cb68", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce827eac3c74295195649d50c0ec3d7744536451d8abaa2940ad9c172d5e45b4d73c2a72bff9236813b43482b2240982feb15e0434f3c31edaa47c6654918481", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/661ec7fadb49e750fe0b2404633e88cd664fa39c09dfaf974f31e0e2a3f005373558d06f690f0dac5c239bbebe23a45690660875b80e86c0ad7a4ba9e4552073", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91fe4b666264bff53143cbf4cf9fe0f928ef764f35532e7b34b6402e85e28ee807e38d1fbff18d6cdf220042ed95f7fa411c6e9849d67273618d8d1c4866edd8"} 18:36:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:36:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:36:38 INFO - Contents: 18:36:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c73b414d969041072580b57187558a2b01177bba988fed12264692975181ecf078801aa3e6a1d9ed393895690c6ffbbde3be1247140d6c6dec8f676c320cb68", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce827eac3c74295195649d50c0ec3d7744536451d8abaa2940ad9c172d5e45b4d73c2a72bff9236813b43482b2240982feb15e0434f3c31edaa47c6654918481", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/661ec7fadb49e750fe0b2404633e88cd664fa39c09dfaf974f31e0e2a3f005373558d06f690f0dac5c239bbebe23a45690660875b80e86c0ad7a4ba9e4552073", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91fe4b666264bff53143cbf4cf9fe0f928ef764f35532e7b34b6402e85e28ee807e38d1fbff18d6cdf220042ed95f7fa411c6e9849d67273618d8d1c4866edd8"} 18:36:38 INFO - Running post-run listener: copy_logs_to_upload_dir 18:36:38 INFO - Copying logs to upload dir... 18:36:38 INFO - mkdir: /builds/slave/test/build/upload/logs 18:36:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.056690 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-09 18:36:38.340973) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 18:36:38.344280) ========= 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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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/9c73b414d969041072580b57187558a2b01177bba988fed12264692975181ecf078801aa3e6a1d9ed393895690c6ffbbde3be1247140d6c6dec8f676c320cb68", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce827eac3c74295195649d50c0ec3d7744536451d8abaa2940ad9c172d5e45b4d73c2a72bff9236813b43482b2240982feb15e0434f3c31edaa47c6654918481", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/661ec7fadb49e750fe0b2404633e88cd664fa39c09dfaf974f31e0e2a3f005373558d06f690f0dac5c239bbebe23a45690660875b80e86c0ad7a4ba9e4552073", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91fe4b666264bff53143cbf4cf9fe0f928ef764f35532e7b34b6402e85e28ee807e38d1fbff18d6cdf220042ed95f7fa411c6e9849d67273618d8d1c4866edd8"} build_url:https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011128 build_url: 'https://queue.taskcluster.net/v1/task/Dvh6ozqSQ2-qEzACRVEIXw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c73b414d969041072580b57187558a2b01177bba988fed12264692975181ecf078801aa3e6a1d9ed393895690c6ffbbde3be1247140d6c6dec8f676c320cb68", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce827eac3c74295195649d50c0ec3d7744536451d8abaa2940ad9c172d5e45b4d73c2a72bff9236813b43482b2240982feb15e0434f3c31edaa47c6654918481", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/661ec7fadb49e750fe0b2404633e88cd664fa39c09dfaf974f31e0e2a3f005373558d06f690f0dac5c239bbebe23a45690660875b80e86c0ad7a4ba9e4552073", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/91fe4b666264bff53143cbf4cf9fe0f928ef764f35532e7b34b6402e85e28ee807e38d1fbff18d6cdf220042ed95f7fa411c6e9849d67273618d8d1c4866edd8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 18:36:38.379139) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 18:36:38.379468) ========= 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.Bz0TqZxSSO/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.zYOgG4K5qZ/Listeners TMPDIR=/var/folders/f2/j76sx_xn7bbccz1zz5dv8zth00000w/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.004595 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 18:36:38.428279) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 18:36:38.428602) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 18:36:38.428954) ========= ========= Total master_lag: 14.60 =========