builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0021 starttime: 1489726575.08 results: success (0) buildid: 20170316211922 builduid: fb938db0964c497ea3c879cebb823a6b revision: dff5f8de5eed5a31fde56f87e8a75fbcc4a2ddff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 21:56:15.082949) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 21:56:15.083969) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 21:56:15.084614) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 21:56:15.113559) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 21:56:15.113958) ========= 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.xySACekJoL/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.HvF7QRkVjn/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-16 21:56:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.2M=0.001s 2017-03-16 21:56:15 (20.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129311 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 21:56:15.280502) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 21:56:15.280865) ========= 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.xySACekJoL/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.HvF7QRkVjn/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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.028232 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 21:56:15.333978) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-16 21:56:15.334735) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dff5f8de5eed5a31fde56f87e8a75fbcc4a2ddff --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dff5f8de5eed5a31fde56f87e8a75fbcc4a2ddff --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xySACekJoL/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.HvF7QRkVjn/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-16 21:56:15,445 truncating revision to first 12 chars 2017-03-16 21:56:15,445 Setting DEBUG logging. 2017-03-16 21:56:15,445 attempt 1/10 2017-03-16 21:56:15,445 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dff5f8de5eed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-16 21:56:17,235 unpacking tar archive at: mozilla-aurora-dff5f8de5eed/testing/mozharness/ program finished with exit code 0 elapsedTime=2.073610 ========= master_lag: 0.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-16 21:56:17.638695) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 21:56:17.639109) ========= script_repo_revision: dff5f8de5eed5a31fde56f87e8a75fbcc4a2ddff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 21:56:17.639570) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 21:56:17.639875) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 21:56:17.735604) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-16 21:56:17.736121) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xySACekJoL/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.HvF7QRkVjn/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 21:56:17 INFO - MultiFileLogger online at 20170316 21:56:17 in /builds/slave/test 21:56:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 21:56:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:56:17 INFO - {'append_to_log': False, 21:56:17 INFO - 'base_work_dir': '/builds/slave/test', 21:56:17 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:56:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:56:17 INFO - 'branch': 'Mozilla-Aurora', 21:56:17 INFO - 'buildbot_json_path': 'buildprops.json', 21:56:17 INFO - 'config_files': ('talos/mac_config.py',), 21:56:17 INFO - 'default_actions': ('clobber', 21:56:17 INFO - 'read-buildbot-config', 21:56:17 INFO - 'download-and-extract', 21:56:17 INFO - 'populate-webroot', 21:56:17 INFO - 'create-virtualenv', 21:56:17 INFO - 'install', 21:56:17 INFO - 'run-tests'), 21:56:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:56:17 INFO - 'download_minidump_stackwalk': True, 21:56:17 INFO - 'download_symbols': 'ondemand', 21:56:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:56:17 INFO - 'tooltool.py': '/tools/tooltool.py', 21:56:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:56:17 INFO - '/tools/misc-python/virtualenv.py')}, 21:56:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:56:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:56:17 INFO - 'gecko_profile': False, 21:56:17 INFO - 'gecko_profile_interval': 0, 21:56:17 INFO - 'installer_path': 'installer.exe', 21:56:17 INFO - 'log_level': 'info', 21:56:17 INFO - 'log_name': 'talos', 21:56:17 INFO - 'log_to_console': True, 21:56:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:56:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:56:17 INFO - 'opt_config_files': (), 21:56:17 INFO - 'pip_index': False, 21:56:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:56:17 INFO - 'cmd': ('bash', 21:56:17 INFO - '-c', 21:56:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:56:17 INFO - 'enabled': True, 21:56:17 INFO - 'halt_on_failure': False, 21:56:17 INFO - 'name': 'check_screen_resolution'},), 21:56:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:56:17 INFO - 'cmd': ('bash', 21:56:17 INFO - '-c', 21:56:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:56:17 INFO - 'enabled': True, 21:56:17 INFO - 'halt_on_failure': False, 21:56:17 INFO - 'name': 'check_screen_resolution'},), 21:56:17 INFO - 'run_cmd_checks_enabled': True, 21:56:17 INFO - 'suite': 'dromaeojs-e10s', 21:56:17 INFO - 'system_bits': '32', 21:56:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:56:17 INFO - 'title': 't-yosemite-r7-0021', 21:56:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:56:17 INFO - 'use_talos_json': True, 21:56:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:56:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:56:17 INFO - 'work_dir': 'build'} 21:56:17 INFO - [mozharness: 2017-03-17 04:56:17.958400Z] Running clobber step. 21:56:17 INFO - Running pre-action listener: _resource_record_pre_action 21:56:17 INFO - Running main action method: clobber 21:56:17 INFO - rmtree: /builds/slave/test/build 21:56:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:56:20 INFO - Running post-action listener: _resource_record_post_action 21:56:20 INFO - [mozharness: 2017-03-17 04:56:20.401952Z] Finished clobber step (success) 21:56:20 INFO - [mozharness: 2017-03-17 04:56:20.402123Z] Running read-buildbot-config step. 21:56:20 INFO - Running pre-action listener: _resource_record_pre_action 21:56:20 INFO - Running main action method: read_buildbot_config 21:56:20 INFO - Using buildbot properties: 21:56:20 INFO - { 21:56:20 INFO - "project": "", 21:56:20 INFO - "product": "firefox", 21:56:20 INFO - "script_repo_revision": "production", 21:56:20 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 21:56:20 INFO - "repository": "", 21:56:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 21:56:20 INFO - "buildid": "20170316211922", 21:56:20 INFO - "pgo_build": "False", 21:56:20 INFO - "basedir": "/builds/slave/test", 21:56:20 INFO - "buildnumber": 12, 21:56:20 INFO - "slavename": "t-yosemite-r7-0021", 21:56:20 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 21:56:20 INFO - "platform": "yosemite_r7", 21:56:20 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 21:56:20 INFO - "branch": "mozilla-aurora", 21:56:20 INFO - "repo_path": "releases/mozilla-aurora", 21:56:20 INFO - "revision": "dff5f8de5eed5a31fde56f87e8a75fbcc4a2ddff", 21:56:20 INFO - "stage_platform": "macosx64", 21:56:20 INFO - "builduid": "fb938db0964c497ea3c879cebb823a6b", 21:56:20 INFO - "slavebuilddir": "test" 21:56:20 INFO - } 21:56:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 21:56:20 INFO - Running post-action listener: _resource_record_post_action 21:56:20 INFO - [mozharness: 2017-03-17 04:56:20.405550Z] Finished read-buildbot-config step (success) 21:56:20 INFO - [mozharness: 2017-03-17 04:56:20.405684Z] Running download-and-extract step. 21:56:20 INFO - Running pre-action listener: _resource_record_pre_action 21:56:20 INFO - Running main action method: download_and_extract 21:56:20 INFO - mkdir: /builds/slave/test/build/tests 21:56:20 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')]} 21:56:20 INFO - trying https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 21:56:20 INFO - Downloading https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 21:56:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 21:56:21 INFO - Downloaded 1199 bytes. 21:56:21 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 21:56:21 INFO - Using the following test package requirements: 21:56:21 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 21:56:21 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:56:21 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 21:56:21 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:56:21 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 21:56:21 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:56:21 INFO - u'jsshell-mac.zip'], 21:56:21 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:56:21 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 21:56:21 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 21:56:21 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:56:21 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 21:56:21 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:56:21 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 21:56:21 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:56:21 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 21:56:21 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:56:21 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 21:56:21 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 21:56:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 21:56:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 21:56:21 INFO - Fetch https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 21:56:22 INFO - Expected file size: 51784296 21:56:22 INFO - Obtained file size: 51784296 21:56:38 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 21:56:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 21:56:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 21:56:38 INFO - Fetch https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 21:56:40 INFO - Expected file size: 51784296 21:56:40 INFO - Obtained file size: 51784296 21:56:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 21:56:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 21:56:55 INFO - Fetch https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 21:56:56 INFO - Expected file size: 14404424 21:56:56 INFO - Obtained file size: 14404424 21:56:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:57 INFO - trying https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 21:56:57 INFO - Downloading https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 21:56:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 21:56:59 INFO - Downloaded 74625128 bytes. 21:56:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 21:56:59 INFO - mkdir: /builds/slave/test/properties 21:56:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:56:59 INFO - Writing to file /builds/slave/test/properties/build_url 21:56:59 INFO - Contents: 21:56:59 INFO - build_url:https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 21:57:00 INFO - Running post-action listener: _resource_record_post_action 21:57:00 INFO - Running post-action listener: set_extra_try_arguments 21:57:00 INFO - [mozharness: 2017-03-17 04:57:00.104790Z] Finished download-and-extract step (success) 21:57:00 INFO - [mozharness: 2017-03-17 04:57:00.104936Z] Running populate-webroot step. 21:57:00 INFO - Running pre-action listener: _resource_record_pre_action 21:57:00 INFO - Running main action method: populate_webroot 21:57:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:57:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:57:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:57:00 INFO - '--tppagecycles', 21:57:00 INFO - '7'], 21:57:00 INFO - 'tests': ['tsvgm']}}, 21:57:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:57:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:57:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:57:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:57:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:57:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:57:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:57:00 INFO - 'talos_options': ['--disable-e10s'], 21:57:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:57:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:57:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:57:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:57:00 INFO - 'talos_options': ['--disable-e10s'], 21:57:00 INFO - 'tests': ['damp', 'tps']}, 21:57:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:57:00 INFO - 'tests': ['damp', 'tps']}, 21:57:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:57:00 INFO - 'tests': ['dromaeo_dom']}, 21:57:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:57:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:57:00 INFO - 'tests': ['basic_compositor_video']}, 21:57:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:57:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:57:00 INFO - 'tests': ['a11yr', 21:57:00 INFO - 'ts_paint', 21:57:00 INFO - 'tpaint', 21:57:00 INFO - 'sessionrestore', 21:57:00 INFO - 'sessionrestore_no_auto_restore', 21:57:00 INFO - 'tabpaint']}, 21:57:00 INFO - 'other-e10s': {'tests': ['a11yr', 21:57:00 INFO - 'ts_paint', 21:57:00 INFO - 'tpaint', 21:57:00 INFO - 'sessionrestore', 21:57:00 INFO - 'sessionrestore_no_auto_restore', 21:57:00 INFO - 'tabpaint']}, 21:57:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:57:00 INFO - 'ts_paint', 21:57:00 INFO - 'tpaint', 21:57:00 INFO - 'sessionrestore', 21:57:00 INFO - 'sessionrestore_no_auto_restore', 21:57:00 INFO - 'tabpaint']}, 21:57:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:57:00 INFO - 'ts_paint', 21:57:00 INFO - 'tpaint', 21:57:00 INFO - 'sessionrestore', 21:57:00 INFO - 'sessionrestore_no_auto_restore', 21:57:00 INFO - 'tabpaint']}, 21:57:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:57:00 INFO - 'tests': ['a11yr', 21:57:00 INFO - 'ts_paint', 21:57:00 INFO - 'tpaint', 21:57:00 INFO - 'sessionrestore', 21:57:00 INFO - 'sessionrestore_no_auto_restore', 21:57:00 INFO - 'tabpaint']}, 21:57:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:57:00 INFO - 'tests': ['a11yr', 21:57:00 INFO - 'ts_paint', 21:57:00 INFO - 'tpaint', 21:57:00 INFO - 'sessionrestore', 21:57:00 INFO - 'sessionrestore_no_auto_restore', 21:57:00 INFO - 'tabpaint']}, 21:57:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:57:00 INFO - 'tests': ['tsvgx', 21:57:00 INFO - 'tsvgr_opacity', 21:57:00 INFO - 'tart', 21:57:00 INFO - 'tscrollx', 21:57:00 INFO - 'cart', 21:57:00 INFO - 'tsvg_static']}, 21:57:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:57:00 INFO - 'tsvgr_opacity', 21:57:00 INFO - 'tart', 21:57:00 INFO - 'tscrollx', 21:57:00 INFO - 'cart', 21:57:00 INFO - 'tsvg_static']}, 21:57:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:57:00 INFO - 'talos_options': ['--disable-e10s'], 21:57:00 INFO - 'tests': ['tp5o']}, 21:57:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:57:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:57:00 INFO - 'talos_options': ['--disable-e10s', 21:57:00 INFO - '--xperf_path', 21:57:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:57:00 INFO - 'tests': ['tp5n']}, 21:57:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:57:00 INFO - 'talos_options': ['--xperf_path', 21:57:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:57:00 INFO - 'tests': ['tp5n']}}, 21:57:00 INFO - 'talos.zip': {'path': '', 21:57:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:57:00 INFO - Running post-action listener: _resource_record_post_action 21:57:00 INFO - [mozharness: 2017-03-17 04:57:00.123525Z] Finished populate-webroot step (success) 21:57:00 INFO - [mozharness: 2017-03-17 04:57:00.123656Z] Running create-virtualenv step. 21:57:00 INFO - Running pre-action listener: _resource_record_pre_action 21:57:00 INFO - Running main action method: create_virtualenv 21:57:00 INFO - Creating virtualenv /builds/slave/test/build/venv 21:57:00 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 21:57:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:57:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:57:00 INFO - Using real prefix '/tools/python27' 21:57:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:57:00 INFO - Installing distribute.............................................................................................................................................................................................done. 21:57:03 INFO - Installing pip.................done. 21:57:03 INFO - Return code: 0 21:57:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:57:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:57:04 INFO - Reading from file tmpfile_stdout 21:57:04 INFO - Output received: 21:57:04 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) 21:57:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:57:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:04 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 0x1085bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf28cbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10839bd30>, '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 0x1082c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108390be8>, '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.xySACekJoL/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.HvF7QRkVjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 21:57:04 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 21:57:04 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 21:57:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xySACekJoL/Render', 21:57:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:57:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:57:04 INFO - 'HOME': '/Users/cltbld', 21:57:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:57:04 INFO - 'LOGNAME': 'cltbld', 21:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:04 INFO - 'MOZ_NO_REMOTE': '1', 21:57:04 INFO - 'NO_EM_RESTART': '1', 21:57:04 INFO - 'PAGER': '/bin/cat', 21:57:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:04 INFO - 'PWD': '/builds/slave/test', 21:57:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:57:04 INFO - 'SHELL': '/bin/bash', 21:57:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HvF7QRkVjn/Listeners', 21:57:04 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 21:57:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:57:04 INFO - 'USER': 'cltbld', 21:57:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:57:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:04 INFO - 'XPC_FLAGS': '0x0', 21:57:04 INFO - 'XPC_SERVICE_NAME': '0', 21:57:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:57:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:04 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 21:57:04 INFO - Cleaning up... 21:57:04 INFO - Return code: 0 21:57:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:57:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:04 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 0x1085bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf28cbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10839bd30>, '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 0x1082c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108390be8>, '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.xySACekJoL/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.HvF7QRkVjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 21:57:04 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 21:57:04 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 21:57:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xySACekJoL/Render', 21:57:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:57:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:57:04 INFO - 'HOME': '/Users/cltbld', 21:57:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:57:04 INFO - 'LOGNAME': 'cltbld', 21:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:04 INFO - 'MOZ_NO_REMOTE': '1', 21:57:04 INFO - 'NO_EM_RESTART': '1', 21:57:04 INFO - 'PAGER': '/bin/cat', 21:57:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:04 INFO - 'PWD': '/builds/slave/test', 21:57:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:57:04 INFO - 'SHELL': '/bin/bash', 21:57:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HvF7QRkVjn/Listeners', 21:57:04 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 21:57:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:57:04 INFO - 'USER': 'cltbld', 21:57:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:57:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:04 INFO - 'XPC_FLAGS': '0x0', 21:57:04 INFO - 'XPC_SERVICE_NAME': '0', 21:57:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:57:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:04 INFO - Downloading/unpacking psutil>=3.1.1 21:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:57:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:57:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:57:08 INFO - Installing collected packages: psutil 21:57:08 INFO - Running setup.py install for psutil 21:57:08 INFO - building 'psutil._psutil_osx' extension 21:57:08 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 21:57:08 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 21:57:08 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 21:57:08 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 21:57:09 INFO - building 'psutil._psutil_posix' extension 21:57:09 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 21:57:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:57:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:57:09 INFO - ^ 21:57:09 INFO - 1 warning generated. 21:57:09 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 21:57:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:57:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:57:09 INFO - Successfully installed psutil 21:57:09 INFO - Cleaning up... 21:57:09 INFO - Return code: 0 21:57:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:57:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:57:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1085bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf28cbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10839bd30>, '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 0x1082c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108390be8>, '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.xySACekJoL/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.HvF7QRkVjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 21:57:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:57:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xySACekJoL/Render', 21:57:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:57:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:57:09 INFO - 'HOME': '/Users/cltbld', 21:57:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:57:09 INFO - 'LOGNAME': 'cltbld', 21:57:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:09 INFO - 'MOZ_NO_REMOTE': '1', 21:57:09 INFO - 'NO_EM_RESTART': '1', 21:57:09 INFO - 'PAGER': '/bin/cat', 21:57:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:57:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:09 INFO - 'PWD': '/builds/slave/test', 21:57:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:57:09 INFO - 'SHELL': '/bin/bash', 21:57:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HvF7QRkVjn/Listeners', 21:57:09 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 21:57:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:57:09 INFO - 'USER': 'cltbld', 21:57:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:57:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:57:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:09 INFO - 'XPC_FLAGS': '0x0', 21:57:09 INFO - 'XPC_SERVICE_NAME': '0', 21:57:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:57:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:57:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:57:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:57:12 INFO - Installing collected packages: mozsystemmonitor 21:57:12 INFO - Running setup.py install for mozsystemmonitor 21:57:12 INFO - Successfully installed mozsystemmonitor 21:57:12 INFO - Cleaning up... 21:57:12 INFO - Return code: 0 21:57:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:57:12 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')]} 21:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:12 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')]} 21:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:13 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 0x1085bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf28cbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10839bd30>, '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 0x1082c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108390be8>, '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.xySACekJoL/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.HvF7QRkVjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 21:57:13 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 21:57:13 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 21:57:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xySACekJoL/Render', 21:57:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:57:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:57:13 INFO - 'HOME': '/Users/cltbld', 21:57:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:57:13 INFO - 'LOGNAME': 'cltbld', 21:57:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:13 INFO - 'MOZ_NO_REMOTE': '1', 21:57:13 INFO - 'NO_EM_RESTART': '1', 21:57:13 INFO - 'PAGER': '/bin/cat', 21:57:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:57:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:13 INFO - 'PWD': '/builds/slave/test', 21:57:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:57:13 INFO - 'SHELL': '/bin/bash', 21:57:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HvF7QRkVjn/Listeners', 21:57:13 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 21:57:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:57:13 INFO - 'USER': 'cltbld', 21:57:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:57:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:57:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:13 INFO - 'XPC_FLAGS': '0x0', 21:57:13 INFO - 'XPC_SERVICE_NAME': '0', 21:57:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:13 INFO - Downloading/unpacking jsonschema==2.5.1 21:57: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 21:57: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 21:57: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 21:57: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 21:57: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 21:57: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 21:57:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:57:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:16 INFO - Downloading functools32-3.2.3-2.tar.gz 21:57:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:57:17 INFO - warning: no files found matching '*.txt' 21:57:17 INFO - no previously-included directories found matching 'build' 21:57:17 INFO - no previously-included directories found matching 'dist' 21:57:17 INFO - no previously-included directories found matching '.git*' 21:57:17 INFO - Installing collected packages: jsonschema, functools32 21:57:17 INFO - Running setup.py install for functools32 21:57:17 INFO - warning: no files found matching '*.txt' 21:57:17 INFO - no previously-included directories found matching 'build' 21:57:17 INFO - no previously-included directories found matching 'dist' 21:57:17 INFO - no previously-included directories found matching '.git*' 21:57:17 INFO - Successfully installed jsonschema functools32 21:57:17 INFO - Cleaning up... 21:57:17 INFO - Return code: 0 21:57:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:57:17 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')]} 21:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:17 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')]} 21:57:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:17 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 0x1085bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf28cbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10839bd30>, '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 0x1082c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108390be8>, '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.xySACekJoL/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.HvF7QRkVjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 21:57:17 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 21:57:17 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 21:57:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xySACekJoL/Render', 21:57:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:57:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:57:17 INFO - 'HOME': '/Users/cltbld', 21:57:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:57:17 INFO - 'LOGNAME': 'cltbld', 21:57:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:17 INFO - 'MOZ_NO_REMOTE': '1', 21:57:17 INFO - 'NO_EM_RESTART': '1', 21:57:17 INFO - 'PAGER': '/bin/cat', 21:57:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:57:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:17 INFO - 'PWD': '/builds/slave/test', 21:57:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:57:17 INFO - 'SHELL': '/bin/bash', 21:57:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HvF7QRkVjn/Listeners', 21:57:17 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 21:57:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:57:17 INFO - 'USER': 'cltbld', 21:57:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:57:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:57:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:17 INFO - 'XPC_FLAGS': '0x0', 21:57:17 INFO - 'XPC_SERVICE_NAME': '0', 21:57:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:57:17 INFO - Cleaning up... 21:57:17 INFO - Return code: 0 21:57:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:57:17 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')]} 21:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:17 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')]} 21:57:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:17 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 0x1085bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf28cbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10839bd30>, '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 0x1082c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108390be8>, '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.xySACekJoL/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.HvF7QRkVjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 21:57:17 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 21:57:17 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 21:57:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xySACekJoL/Render', 21:57:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:57:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:57:17 INFO - 'HOME': '/Users/cltbld', 21:57:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:57:17 INFO - 'LOGNAME': 'cltbld', 21:57:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:17 INFO - 'MOZ_NO_REMOTE': '1', 21:57:17 INFO - 'NO_EM_RESTART': '1', 21:57:17 INFO - 'PAGER': '/bin/cat', 21:57:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:57:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:17 INFO - 'PWD': '/builds/slave/test', 21:57:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:57:17 INFO - 'SHELL': '/bin/bash', 21:57:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HvF7QRkVjn/Listeners', 21:57:17 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 21:57:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:57:17 INFO - 'USER': 'cltbld', 21:57:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:57:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:57:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:17 INFO - 'XPC_FLAGS': '0x0', 21:57:17 INFO - 'XPC_SERVICE_NAME': '0', 21:57:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:17 INFO - Downloading/unpacking blobuploader==1.2.4 21:57: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 21:57: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 21:57: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 21:57: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 21:57: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 21:57: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 21:57:20 INFO - Downloading blobuploader-1.2.4.tar.gz 21:57:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:57:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:57:20 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 21:57:20 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 21:57:20 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 21:57:20 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 21:57:20 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 21:57:20 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 21:57:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:57:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:57:21 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 21:57:21 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 21:57:21 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 21:57:21 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 21:57:21 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 21:57:21 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 21:57:21 INFO - Downloading docopt-0.6.1.tar.gz 21:57:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:57:21 INFO - Installing collected packages: blobuploader, requests, docopt 21:57:21 INFO - Running setup.py install for blobuploader 21:57:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:57:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:57:21 INFO - Running setup.py install for requests 21:57:22 INFO - Running setup.py install for docopt 21:57:22 INFO - Successfully installed blobuploader requests docopt 21:57:22 INFO - Cleaning up... 21:57:22 INFO - Return code: 0 21:57:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:57:22 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')]} 21:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:22 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')]} 21:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:22 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 0x1085bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf28cbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10839bd30>, '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 0x1082c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108390be8>, '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.xySACekJoL/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.HvF7QRkVjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 21:57:22 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 21:57:22 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 21:57:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xySACekJoL/Render', 21:57:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:57:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:57:22 INFO - 'HOME': '/Users/cltbld', 21:57:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:57:22 INFO - 'LOGNAME': 'cltbld', 21:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:22 INFO - 'MOZ_NO_REMOTE': '1', 21:57:22 INFO - 'NO_EM_RESTART': '1', 21:57:22 INFO - 'PAGER': '/bin/cat', 21:57:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:22 INFO - 'PWD': '/builds/slave/test', 21:57:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:57:22 INFO - 'SHELL': '/bin/bash', 21:57:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HvF7QRkVjn/Listeners', 21:57:22 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 21:57:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:57:22 INFO - 'USER': 'cltbld', 21:57:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:57:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:22 INFO - 'XPC_FLAGS': '0x0', 21:57:22 INFO - 'XPC_SERVICE_NAME': '0', 21:57:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:57:22 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-YiTI3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:57:23 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-gl4d4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:57:23 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-fYWyde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:57:23 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-BuOwM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:57:23 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-MjTsbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:57:23 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-Cq_k9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:57:23 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-kdZnfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:57:23 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-SN4Tac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:57:23 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-pkC3mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:57:23 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-MVvS85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:57:23 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-sX5DiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:57:24 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-ImCnw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:57:24 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-zTEKa9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:57:24 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-BBH75H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:57:24 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-RoJTQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:57:24 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-SuSKwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:57:24 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-cIRqog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:57:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:57:24 INFO - Running setup.py install for manifestparser 21:57:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:57:24 INFO - Running setup.py install for mozcrash 21:57:24 INFO - Running setup.py install for mozdebug 21:57:25 INFO - Running setup.py install for mozdevice 21:57:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:57:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:57:25 INFO - Running setup.py install for mozfile 21:57:25 INFO - Running setup.py install for mozhttpd 21:57:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:57:25 INFO - Running setup.py install for mozinfo 21:57:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:57:25 INFO - Running setup.py install for mozInstall 21:57:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:57:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:57:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:57:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:57:25 INFO - Running setup.py install for mozleak 21:57:26 INFO - Running setup.py install for mozlog 21:57:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:57:26 INFO - Running setup.py install for moznetwork 21:57:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:57:26 INFO - Running setup.py install for mozprocess 21:57:26 INFO - Running setup.py install for mozprofile 21:57:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:57:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:57:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:57:26 INFO - Running setup.py install for mozrunner 21:57:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:57:26 INFO - Running setup.py install for mozscreenshot 21:57:26 INFO - Running setup.py install for moztest 21:57:27 INFO - Running setup.py install for mozversion 21:57:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:57:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:57:27 INFO - Cleaning up... 21:57:27 INFO - Return code: 0 21:57:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:57:27 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')]} 21:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:27 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')]} 21:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:27 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 0x1085bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf28cbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10839bd30>, '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 0x1082c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108390be8>, '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.xySACekJoL/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.HvF7QRkVjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 21:57:27 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 21:57:27 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 21:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xySACekJoL/Render', 21:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:57:27 INFO - 'HOME': '/Users/cltbld', 21:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:57:27 INFO - 'LOGNAME': 'cltbld', 21:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:27 INFO - 'MOZ_NO_REMOTE': '1', 21:57:27 INFO - 'NO_EM_RESTART': '1', 21:57:27 INFO - 'PAGER': '/bin/cat', 21:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:27 INFO - 'PWD': '/builds/slave/test', 21:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:57:27 INFO - 'SHELL': '/bin/bash', 21:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HvF7QRkVjn/Listeners', 21:57:27 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 21:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:57:27 INFO - 'USER': 'cltbld', 21:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:27 INFO - 'XPC_FLAGS': '0x0', 21:57:27 INFO - 'XPC_SERVICE_NAME': '0', 21:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:57:27 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-xTMWfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:57:27 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)) 21:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:57:27 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-OR5oyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:57:27 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)) 21:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:57:27 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-W6jfSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:57:27 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)) 21:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:57:27 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-EqoW6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:57:27 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)) 21:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:57:27 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-8Voqyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:57:28 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)) 21:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:57:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-A6EiKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:57:28 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)) 21:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:57:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-Zhxtwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:57:28 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)) 21:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:57:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-vKjo6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:57:28 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)) 21:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:57:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-zfc5mT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:57:28 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)) 21:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:57:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-8m2NQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:57:28 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)) 21:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:57:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-d3l80Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:57:28 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)) 21:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:57:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-6vw7kE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:57:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:57:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-X0AGve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:57:28 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)) 21:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:57:28 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-PkTqxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:57:29 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)) 21:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:57:29 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-zdc3rY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:57:29 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)) 21:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:57:29 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-akV82Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:57:29 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)) 21:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:57:29 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-mPLjJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:57:29 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)) 21:57:29 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)) 21:57:29 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)) 21:57:29 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)) 21:57:29 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)) 21:57:29 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)) 21:57:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:57:29 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 21:57:29 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 21:57:29 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 21:57:29 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 21:57:29 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 21:57:29 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 21:57:31 INFO - Downloading blessings-1.6.tar.gz 21:57:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:57:32 INFO - Installing collected packages: blessings 21:57:32 INFO - Running setup.py install for blessings 21:57:32 INFO - Successfully installed blessings 21:57:32 INFO - Cleaning up... 21:57:32 INFO - Return code: 0 21:57:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:57:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:57:32 INFO - Reading from file tmpfile_stdout 21:57:32 INFO - Current package versions: 21:57:32 INFO - blessings == 1.6 21:57:32 INFO - blobuploader == 1.2.4 21:57:32 INFO - docopt == 0.6.1 21:57:32 INFO - functools32 == 3.2.3-2 21:57:32 INFO - jsonschema == 2.5.1 21:57:32 INFO - manifestparser == 1.1 21:57:32 INFO - mozInstall == 1.12 21:57:32 INFO - mozcrash == 1.0 21:57:32 INFO - mozdebug == 0.1 21:57:32 INFO - mozdevice == 0.49 21:57:32 INFO - mozfile == 1.2 21:57:32 INFO - mozhttpd == 0.7 21:57:32 INFO - mozinfo == 0.9 21:57:32 INFO - mozleak == 0.1 21:57:32 INFO - mozlog == 3.4 21:57:32 INFO - moznetwork == 0.27 21:57:32 INFO - mozprocess == 0.25 21:57:32 INFO - mozprofile == 0.28 21:57:32 INFO - mozrunner == 6.13 21:57:32 INFO - mozscreenshot == 0.1 21:57:32 INFO - mozsystemmonitor == 0.3 21:57:32 INFO - moztest == 0.8 21:57:32 INFO - mozversion == 1.4 21:57:32 INFO - psutil == 3.1.1 21:57:32 INFO - requests == 1.2.3 21:57:32 INFO - wsgiref == 0.1.2 21:57:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:57:32 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')]} 21:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:32 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')]} 21:57:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:32 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 0x1085bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf28cbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10839bd30>, '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 0x1082c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108390be8>, '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.xySACekJoL/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.HvF7QRkVjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 21:57:32 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 21:57:32 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 21:57:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xySACekJoL/Render', 21:57:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:57:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:57:32 INFO - 'HOME': '/Users/cltbld', 21:57:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:57:32 INFO - 'LOGNAME': 'cltbld', 21:57:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:32 INFO - 'MOZ_NO_REMOTE': '1', 21:57:32 INFO - 'NO_EM_RESTART': '1', 21:57:32 INFO - 'PAGER': '/bin/cat', 21:57:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:57:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:32 INFO - 'PWD': '/builds/slave/test', 21:57:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:57:32 INFO - 'SHELL': '/bin/bash', 21:57:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HvF7QRkVjn/Listeners', 21:57:32 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 21:57:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:57:32 INFO - 'USER': 'cltbld', 21:57:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:57:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:57:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:32 INFO - 'XPC_FLAGS': '0x0', 21:57:32 INFO - 'XPC_SERVICE_NAME': '0', 21:57:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:57:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:32 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)) 21:57:32 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)) 21:57:32 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)) 21:57:32 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)) 21:57:32 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)) 21:57:32 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)) 21:57:32 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)) 21:57:32 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)) 21:57:32 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)) 21:57:32 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)) 21:57:32 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)) 21:57:32 INFO - Cleaning up... 21:57:32 INFO - Return code: 0 21:57:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:57:32 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')]} 21:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:32 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')]} 21:57:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:32 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 0x1085bdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1085c0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf28cbe9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10839bd30>, '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 0x1082c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108390be8>, '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.xySACekJoL/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.HvF7QRkVjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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 21:57:32 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 21:57:32 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 21:57:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xySACekJoL/Render', 21:57:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:57:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:57:32 INFO - 'HOME': '/Users/cltbld', 21:57:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:57:32 INFO - 'LOGNAME': 'cltbld', 21:57:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:32 INFO - 'MOZ_NO_REMOTE': '1', 21:57:32 INFO - 'NO_EM_RESTART': '1', 21:57:32 INFO - 'PAGER': '/bin/cat', 21:57:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:57:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:32 INFO - 'PWD': '/builds/slave/test', 21:57:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:57:32 INFO - 'SHELL': '/bin/bash', 21:57:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HvF7QRkVjn/Listeners', 21:57:32 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 21:57:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:57:32 INFO - 'USER': 'cltbld', 21:57:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:57:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:57:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:32 INFO - 'XPC_FLAGS': '0x0', 21:57:32 INFO - 'XPC_SERVICE_NAME': '0', 21:57:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:57:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:57:33 INFO - Cleaning up... 21:57:33 INFO - Return code: 0 21:57:33 INFO - Running post-action listener: _resource_record_post_action 21:57:33 INFO - Running post-action listener: _start_resource_monitoring 21:57:33 INFO - Starting resource monitoring. 21:57:33 INFO - [mozharness: 2017-03-17 04:57:33.197702Z] Finished create-virtualenv step (success) 21:57:33 INFO - [mozharness: 2017-03-17 04:57:33.198653Z] Running install step. 21:57:33 INFO - Running pre-action listener: _resource_record_pre_action 21:57:33 INFO - Running main action method: install 21:57:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:57:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:57:33 INFO - Reading from file tmpfile_stdout 21:57:33 INFO - Detecting whether we're running mozinstall >=1.0... 21:57:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:57:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:57:33 INFO - Reading from file tmpfile_stdout 21:57:33 INFO - Output received: 21:57:33 INFO - Usage: mozinstall [options] installer 21:57:33 INFO - Options: 21:57:33 INFO - -h, --help show this help message and exit 21:57:33 INFO - -d DEST, --destination=DEST 21:57:33 INFO - Directory to install application into. [default: 21:57:33 INFO - "/builds/slave/test"] 21:57:33 INFO - --app=APP Application being installed. [default: firefox] 21:57:33 INFO - mkdir: /builds/slave/test/build/application 21:57:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:57:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 21:57:53 INFO - Reading from file tmpfile_stdout 21:57:53 INFO - Output received: 21:57:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 21:57:53 INFO - Running post-action listener: _resource_record_post_action 21:57:53 INFO - [mozharness: 2017-03-17 04:57:53.460109Z] Finished install step (success) 21:57:53 INFO - [mozharness: 2017-03-17 04:57:53.460253Z] Running run-tests step. 21:57:53 INFO - Running pre-action listener: _resource_record_pre_action 21:57:53 INFO - Running main action method: run_tests 21:57:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:57:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:57:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:57:53 INFO - 2017-03-16 21:57:53.474 screenresolution[1633:7299] starting screenresolution argv=screenresolution get 21:57:53 INFO - 2017-03-16 21:57:53.477 screenresolution[1633:7299] Display 0: 1600x1200x32@60 21:57:53 INFO - 2017-03-16 21:57:53.483 screenresolution[1634:7301] starting screenresolution argv=screenresolution list 21:57:53 INFO - Available Modes on Display 0 21:57:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:57:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:57:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:57:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:57:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:57:53 INFO - Intel Iris: 21:57:53 INFO - Chipset Model: Intel Iris 21:57:53 INFO - Type: GPU 21:57:53 INFO - Bus: Built-In 21:57:53 INFO - VRAM (Dynamic, Max): 1536 MB 21:57:53 INFO - Vendor: Intel (0x8086) 21:57:53 INFO - Device ID: 0x0a2e 21:57:53 INFO - Revision ID: 0x0009 21:57:53 INFO - Displays: 21:57:53 INFO - Display: 21:57:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:57:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:57:53 INFO - Display Serial Number: 1600x1200 60 21:57:53 INFO - Main Display: Yes 21:57:53 INFO - Mirror: Off 21:57:53 INFO - Online: Yes 21:57:53 INFO - Rotation: Supported 21:57:53 INFO - Return code: 0 21:57:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:57:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:57:53 INFO - Python 2.7.3 21:57:53 INFO - Return code: 0 21:57:53 INFO - grabbing minidump binary from tooltool 21:57: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')]} 21:57: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 0x10839bd30>, '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 0x1082c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108390be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:57: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 21:57: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 21:57: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 21:57:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:57:53 INFO - Return code: 0 21:57:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:57:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:57:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:57:53 INFO - ENV: RUST_BACKTRACE is now 1 21:57:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:57:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:57:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0021', '--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 21:57:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0021 --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 21:57:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xySACekJoL/Render', 21:57:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:57:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:57:53 INFO - 'HOME': '/Users/cltbld', 21:57:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:57:53 INFO - 'LOGNAME': 'cltbld', 21:57:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:57:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:57:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:53 INFO - 'MOZ_NO_REMOTE': '1', 21:57:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:57:53 INFO - 'NO_EM_RESTART': '1', 21:57:53 INFO - 'PAGER': '/bin/cat', 21:57:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:57:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:53 INFO - 'PWD': '/builds/slave/test', 21:57:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:57:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:57:53 INFO - 'RUST_BACKTRACE': '1', 21:57:53 INFO - 'SHELL': '/bin/bash', 21:57:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HvF7QRkVjn/Listeners', 21:57:53 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 21:57:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:57:53 INFO - 'USER': 'cltbld', 21:57:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:57:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:57:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:53 INFO - 'XPC_FLAGS': '0x0', 21:57:53 INFO - 'XPC_SERVICE_NAME': '0', 21:57:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:57:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0021', '--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 21:57:54 INFO - mozversion application_buildid: 20170316211922 21:57:54 INFO - mozversion application_changeset: dff5f8de5eed5a31fde56f87e8a75fbcc4a2ddff 21:57:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:57:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:57:54 INFO - mozversion application_name: Firefox 21:57:54 INFO - mozversion application_remotingname: firefox-dev 21:57:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:57:54 INFO - mozversion application_vendor: Mozilla 21:57:54 INFO - mozversion application_version: 54.0a2 21:57:54 INFO - mozversion platform_buildid: 20170316211922 21:57:54 INFO - mozversion platform_changeset: dff5f8de5eed5a31fde56f87e8a75fbcc4a2ddff 21:57:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:57:54 INFO - mozversion platform_version: 54.0a2 21:57:54 INFO - using testdate: 1489726674 21:57:54 INFO - actual date: 1489726674 21:57:54 INFO - starting webserver on 'localhost:49235' 21:57:54 INFO - SUITE-START | Running 2 tests 21:57:54 INFO - TEST-START | dromaeo_css 21:57:54 INFO - operating with platform_type : mac_ 21:57:54 INFO - Initialising browser for dromaeo_css test... 21:57:54 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmp8XS83Z/profile http://localhost:49235/getInfo.html) 21:57:57 INFO - PROCESS | 1644 | 2017-03-16 21:57:57.070 plugin-container[1646:7494] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 21:57:57 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:57:57 INFO - PROCESS | 1644 | 2017-03-16 21:57:57.074 plugin-container[1646:7494] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c1f, name = 'com.apple.CFPasteboardClient' 21:57:57 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:57:57 INFO - PROCESS | 1644 | 2017-03-16 21:57:57.074 plugin-container[1646:7494] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:57:57 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 21:57:57 INFO - PROCESS | 1644 | colorDepth:24 21:57:57 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 21:57:57 INFO - PROCESS | 1644 | __metrics 21:57:57 ERROR - PROCESS | 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 21:57:58 INFO - TEST-INFO | 1644: exit 0 21:57:58 INFO - Browser initialized. 21:57:58 INFO - Running cycle 1/1 for dromaeo_css test... 21:57:58 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmp8XS83Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:58:00 INFO - PROCESS | 1647 | 2017-03-16 21:58:00.424 plugin-container[1649:7626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 21:58:00 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:58:00 INFO - PROCESS | 1647 | 2017-03-16 21:58:00.425 plugin-container[1649:7626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 21:58:00 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:58:00 INFO - PROCESS | 1647 | 2017-03-16 21:58:00.425 plugin-container[1649:7626] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:58:10 INFO - PROCESS | 1647 | RSS: Main: 220110848 21:58:10 INFO - PROCESS | 1647 | 22:01:13 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 22:01:15 INFO - PROCESS | 1647 | RSS: Main: 239931392 22:01:15 INFO - PROCESS | 1647 | 22:02:58 INFO - PROCESS | 1647 | ************************* 22:02:58 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 22:02:58 INFO - PROCESS | 1647 | 22:02:58 ERROR - PROCESS | 1647 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:02:58 INFO - PROCESS | 1647 | Full stack: 22:02:58 INFO - PROCESS | 1647 | ************************* 22:04:17 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 22:04:18 INFO - PROCESS | 1647 | RSS: Main: 250036224 22:04:18 INFO - PROCESS | 1647 | 22:07:19 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 22:07:20 INFO - PROCESS | 1647 | RSS: Main: 255856640 22:07:20 INFO - PROCESS | 1647 | 22:10:22 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 22:10:23 INFO - PROCESS | 1647 | RSS: Main: 252231680 22:10:23 INFO - PROCESS | 1647 | 22:13:26 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 22:13:26 INFO - PROCESS | 1647 | RSS: Main: 256946176 22:13:26 INFO - PROCESS | 1647 | 22:16:29 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 22:16:29 INFO - PROCESS | 1647 | RSS: Main: 253284352 22:16:29 INFO - PROCESS | 1647 | 22:16:29 INFO - PROCESS | 1647 | __start_tp_report 22:16:29 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 22:16:29 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 22:16:29 INFO - PROCESS | 1647 | |i|pagename|runs| 22:16:29 INFO - PROCESS | 1647 | |0;dojo.html;2667.7065522792477;3107.238726512005;3496.91257718557;3510.719142468603;3513.4378499440095;6015.789447369343;6053.969730151343;6071.817845464638;6091.177691011712;6091.756329746805;6029.849253768658;6067.393260673931;6100.572959892809;6112.6638034908065;6162.568620196588;5410.08028635132;5482.725863706799;5485.807996720116;5519.9448005520035;5600;5536.390996990328;5544.584156188282;5549.19536667183;5550.639208451458;5577.247071645337;5326.041312563737;5330.786768529254;5547.084731019377;5558.805441809537;5565.582581306418;4951.63829946764;5138.614603904684;5419.864503387407;5455.672659640434;5496.450354964471;5331.040412725708;5434.91847622286;5470;5495.120780675073;5509.724513774336;5790.565707571906;6023.578349515536;6113.877722445526;6115.571909966304;6130.2030736004035;6289.371062893674;6294.968525157345;6326.398992095744;6327.493800495995;6342.39747224013;6542.084108224853;6579.078928949951;6580.14458120446;6584.93415065848;6587.637679927602;6180.072989051589;6204.751809927642;6219.067139929001;6243;6244.344343843922;6440.0661904023655;6488.383603557749;6624.6025238485845;6625.569337993015;6740.764073257511;5348.893022139536;6382.074599183093;6412.326705695835;6431.42117209444;6439.195100612424;6458;6520.967395162994;6545.312742162099;6551.901721474182;6575.342465753386;6255.155554000243;6303.369663033752;6467.741290348334;6483.643399613066;6502.382273683992;6240.875182496325;6336.619802811846;6355.904661430082;6386.712597933104;6422.2614399344675;4770.212914869077;5099.515546023122;5144.176931690987;5147.124988751922;5251.1598144297495;4635.258358662598;4656.301554766812;4659.6505262105475;4665.976670116627;4676.228422310349;5978.8206353809455;6153.2000839891425;6241.594296370812;6245.156903818017;6281.623102613858;5342.9465705342445;6364.109024736541;6394.488440924715;6462.547621666438;6536.117624120683;6223.719932603044;6224.408681175281;6237.064440333987;6245.18812554365;6257.186565746424;4761.309610106515;5758.078707406795;5775.220345253421;5783.59514833966;5795.014847475939;6426.164598602152;6487.0593763903025;6683.264840867598;6693.263740988585;6802.421794147553;5569.443055694398;6593.637349945799;6620.304867988792;6623.735050597922;6626.83432914181;6721.93278067213;6829.965850170717;6831.180258369028;6838.589684618939;6858.245592984668;5246.396664383645;5922.73347699346;6203.379662033941;6269.62382257066;6358.523110766803;5745.569082318759;6308;6342.809715708536;6463.967680161569;6504.414602685781;6325.778597749057;6351.142595749515;6376.553641245254;6378.585391949508;6427.742890284337;6160.137580738789;6272.874542509216;6273.592216505913;6291.4568848398985;6366.267879193952;6067.393260673897;6194.0708893664505;6249.78125765596;6269.843253918506;6277.591956522811;5299.364076310867;6376.521760868046;6378.6810659467765;6391.808245752635;6464.321246269262;6107.037103281039;6140.140380346845;6200.193974783249;6231.501479881508;6281.905771413524 22:16:29 INFO - PROCESS | 1647 | |1;ext.html;702.3012102957557;752.5371896283783;823.8352329534091;959.6257459590756;968.7045451137409;37372;38322.808385958066;39868;39980.60019399805;40116.19767604646;38438.61561384385;38649.03377415566;38701.806490967574;38783.80608096955;38841;7852.097008843984;7948.4436089473475;10587.894121058805;10883.292585981886;10908.127349812035;10533.26267161299;10596.841047384296;10650.147988160967;10664.520096595634;10668.413237271947;10604.628837990673;10687.198460115522;10704.090152337061;10711;10714.250002499828;10589.099926506256;10607.3105248159;10639.946800265989;10648.946755266174;10664.786704265949;10524.842127368096;10574.154067674644;10579.100776434012;10584;10587.311824731369;33669.494957575655;33736.83131584351;33854.49218261728;33855.8150464735;33904.49143262853;3622.710183185339;3687.3362794696945;3696.7227457940494;3706.592274849764;3739.66343029126;24597.754022459725;24756.25731228066;25202.11792587269;25981.74018259812;26181.214563563124;3010.1186758276076;3038.9696103039128;3123.7657175711684;3190.840457977115;3220.516922461626;346205;362483;368548;369216;369740;44215.89460263519;44872.55127448749;44957.775211123735;45002.54997449984;45012.3248151278;46031;46653;46703.06593868104;46820.06359872784;46879;12565.434555445026;12579.433925473375;12747.298898560668;12752.426140823685;12761.68095797594;10586;10586.788264234827;10597.20520960931;10605.681829544972;10620.89379106199;9339.533023348806;9777.608895644236;12005.819912701316;12126.333051682066;12132.818007729891;4294.506131794884;5481.75332110056;5588.2455868458055;5744.253247077937;5756.0502517084215;922.7831459606987;926.7636752628036;981.4552923127733;983.4148681534475;1040.959040959041;26907.461850763266;28647.85408583675;29288.121356359345;29409.97065102754;29530.409391812045;938.5119737736339;988.6885631026204;1012.6860673191187;1056.9947150264354;1065.4086981725218;14630.268486575842;15069.773953390708;15128.243587820565;15364.462243821412;15394.692106157814;506414;506898;507856;509867;511922;47229;49042.75478622656;57210.71394643;57697.711511442176;57705;1377.0635967541966;1402.726468338664;1404.557564367221;1415.916823629924;1423.1034448297507;25725.070619703223;26393;27055;27526;27561.862190688917;39778.60221397749;39943.80028099841;40216.994575135854;40261.59738402579;40406.79796600998;466496;507430;508931;515652;516857;29347.85326073356;29618.815247389863;29629.703702962695;29879.55180672335;29918.401631966804;36360.27279454343;36902.07744806401;37381;37718.43422348722;37776.43335349917;31063.0681079568;31170.22074448157;31245.687543125186;31934.68065319317;32224.35551289008;24873.875630621733;30905.8454707734;31240;31569.52645710362;31877.681223187472;28201.294967625974;28997.806142800495;29122.854385727936;29270.975515856844;29323.7067629321;26895.865520672272;27339;28007.159785206477;28490;28804.694349246565;20484.692729609367;21634.350969470943;21658.67511987353;21834.344969650934;22009.559808803413 22:16:29 INFO - PROCESS | 1647 | |2;jquery.html;379.9867004654837;413.6050072181067;413.6959334888856;422.48034917052024;449.5302408982613;19748.802511974893;20084.8953307568;21161.15355385783;21252.36242912711;21392.037358318874;15938.920305398486;19072.618547629037;19138.043097845122;21406.929653517338;21410.46473838155;5679.7160141992945;6243.157173781549;6481.254655714606;6510.869782604345;6521.184851893513;5581.748821303031;6618.139641846553;6620.735170593194;6621.470282377399;6750.8312292192595;6050.757969681229;6473.579217350881;6686.732530698766;6689.2976237495095;6689.73241070359;6667.533272670915;7137.250588688191;7540.359069479101;7650.7704768856465;7667.846643067108;7190.712371505134;7346.155192152917;7350.264973502661;7423.109226892753;7437.144728356254;25431.491370172494;25554.23337299884;26092.86953565239;26192.34519137037;26646.866765666047;3143.654198038214;3237.8866739664;3324.983375083133;3332.8833490827706;3350.8177412673576;12248.836360545734;13710.588682339545;15837.445689400816;16396.50810475676;16521.586960325967;1676.2959556986095;1738.8695847811464;1996.0818023709112;2001.7917579135858;2003.5692326149947;158327;159346;163078;164182;168532.3146768529;20399.694004589943;20537.281195158397;20641.864697441484;20759.68860467094;20970.37870266217;31130.844345778125;31459.898903538266;31464.685353146633;31541.52687709686;31632.525512117336;9807.26445516589;9818.165455936325;9927.305088643725;9963.053509916546;10101.040401161878;8161.67353305873;8168.06067302256;8180.345572354197;8330.750077497685;8331.458455200393;7644.827396630336;9407.529623518796;9504.192143667728;9568;9629.277804164716;4033.1933613276874;4084.5302790179508;4127.174565086934;4130.772807495617;4139.19285739278;983.9803203936025;995.6913356859396;998.9400635961865;1014.5637375928421;1022.7801022779989;14457;19997.700034499496;20934.47663808417;21009.68485472719;21213.363599092052;983.685220729374;984.2224642532462;1012.336919317848;1013.8124446977333;1021.5249908792299;10684.038436540748;10896.727581810519;10977.560897564168;11010.889891100986;11024.118070554336;768147;782082;793989;804638;846559;144195;164915;172924;173987.8208525391;178210.3268450974;1207.619599826052;1247.1270110922392;1281.2568710148091;1294.4433893425917;1354.695193581456;18520.722189166903;19101.617967640846;20201.898990504953;20792.58414831725;20838.791612084293;53081.46918530765;63262.102136935966;65944;66178.66910665415;66509.33490665031;94042.05957940333;94177;94320.52839735757;95140;96613.51693241489;11435.771284574428;11720.179587428798;11882.28706277626;12267.447964841425;12288.262704237774;12138.696532586757;12715.237085774883;12848.743025139633;13073.411696473491;13449.394777234853;11005;11580.594679186188;11608.401395567402;11637.941810290895;11686.474108665301;9014.459132452073;9383.812323753624;10690.57237710514;10864.619738309122;11135.164862635496;9645.42127472354;11563.768724625628;11672.47473863695;11853.34806585646;12011;9834.557444915139;11592.362420066976;11906.583269585522;12074.094442916641;12208;11340.586943501829;11424.543018279175;11537.653870383901;11684.941575292069;11833.585824496102 22:16:30 INFO - PROCESS | 1647 | |3;mootools.html;1453.16443045249;1523.12420358294;1557.883158763093;1585.6511567455157;1605.7591361295808;8903.42127761695;9534.475603841785;10637.57449702012;11235.21353505255;11333.829992550109;10570.630027949024;10822;10858.945705271462;10888.891111088888;10902.672919812398;2382.3091303522;2447.363685441789;2451.816113791464;2456.8403053801535;2460.2988148377717;2394.0663141374876;2428.8299819012677;2428.9149879754214;2430.6718592989987;2431.927042188728;2398.988005059981;2407.0973384980634;2419.2863105383867;2426.4904370082304;2433.0024689877155;2329.580675478413;2367.532130079353;2370.158593699229;2372.3713215997773;2372.5847976604114;2327.49958758866;2336.778006089419;2389.426537630956;2395.7125144982715;2397.11306816479;11043.668689939315;11126.165537584713;11142.832857507063;11159.497822598003;11181;5811.709414529257;5957.910631340534;5977.790777322816;5982.8504287392725;5983.820485385445;3060.3879224155257;3537.8407971641336;3562.4300111982216;3680.9509289852263;3690.538682664667;1155.2029099921112;1176.6175992802373;1193.928364298145;1214.0409076829383;1231.5381731850557;15418.152001639904;15538.223088845512;15550.844491555054;15553.688926221525;15568.61078473036;4685.570900875266;4901.975490122527;4934.8766280843265;4961.206207006861;5038.571721403649;12698.238105713686;12777.361131943366;12778.936105319413;12780;12791.680207994874;5319.175527793198;5634.830955071355;5898.8230353089475;5991.191189189491;6000.909986350208;4360.127974405132;4999.600031997432;5167.870803229949;5237;5345.9732701337025;4112.896841105234;4140.420341152241;4186.91626167475;4195.286801243796;4199.0552125772065;1005.4620777883944;1161.1117495116243;1163.2202729823384;1163.4764356039816;1172.6013155527162;808.9281701745211;859.3297228162044;889.6130183370125;898.8112496375834;921.5576523268868;5664.433556644302;6374.948133558004;6806.557573757789;6852.794416167523;6884.139482564679;830.543201239316;944.8441007233866;978.2272005115879;1006.8389057750725;1059.4914441068331;4170.332746760504;4288.442502474721;4417.624501917373;4434.778261086933;4532.818687252539;13526.661833454242;14014.509492167725;14086.366113524917;14097.154170749787;14123.505677301451;13639.863601364057;13713;13903.4438622456;13999.860001400059;14032.789508157386;9922.305438619227;10361.378317300987;10373.118284945713;10448.32085914413;10473.528691208914;6411.070394792636;6462.418382345423;6545;6582.045603387578;6618.801435956929;12278.75442491163;12472.064595155218;12487.313197773847;12489;12596.496140154657;12485.375731213584;12651.051171162384;12851.16467429614;12992.675183120498;13009.544665937025;7465.701371945279;7715.305622494003;8308.5430301334;8372.246497815226;8632;10413.635522756667;10415.42715150674;10487.895121048692;10527.368357898551;10531.05220530156;9217.44695318283;9237.12247336502;9371.953140234255;9375.343725939392;9386.108319709616;8937.910620893708;9040.141186587263;9102.271818254392;9110.635574576943;9131.634734610807;7876.763697088867;7919.564423956737;7965.163657815846;8006.759797206093;8009;5602.915956260578;5670.943290567042;5690.118031705092;5857.560682948882;5878.853028674317;4942.827001054946;6017.18767966319;6017.217761690952;6054;6125.418085281891 22:16:30 INFO - PROCESS | 1647 | |4;prototype.html;372.26849241240956;373.701039168665;381.1595432072279;393.6063936063936;395.60044355201245;5115.462876397975;5267.841964741059;5351.983123206588;5387.16498942664;5546.750396232169;4318.201132790436;4393.7803109844535;4582.5188355222635;5269.707677160438;5273.472652734734;3503.6321186275454;3816.751911125782;4229.936550951738;4407.2507673695545;4417.845375411861;4086.9386959195635;4286.807093680776;4404.7357158570585;4426.911461770779;4469.351943968123;4447.266201076818;4447.66642501812;4448.911021779562;4463.531329210435;4478.283474644058;4172.582741725833;4299.484061912559;4376.803043863034;4377.78111094444;4442.8667139985855;4009.6190861868076;4033.2740106780766;4033.9915021244688;4040;4072.3891416287497;4327.766586522857;4735.377436261263;4843.062774890037;5133.768980395891;5189.584833213334;4269.719084274705;4811.326414302001;5086.601184674208;5128.205128205137;5150.253213284052;5480.479354461319;5570.857974115337;5698.886022279573;5707.259285917793;5764.625299355528;4532.41652258213;4550.453945526525;4558.954410455886;4579.519150489184;4587.761304447814;5799.710014499258;6045;6046.304674962392;6098.908516372258;6140.570160088841;4109.445224894661;4588.701734387254;4692.835750748707;4696.929546056744;4708.387909571734;4660.067986402733;4794.472608013116;4796.664233503691;4830.7101573905675;4844.806207751651;4657.532877143689;4889.877753056131;5047.899042019212;5066.721330326867;5083.991823229803;5544.168374743821;5686.3744988051285;5687.289088863892;5738.307199376194;5757.309122905194;1592.6018495376156;1614.7093523165943;1616.3211451190293;1619.4372430604524;1633.3747920818623;1165.9941700291613;1189.2210602055668;1206.776746301937;1211.2066596379386;1230.329470438613;4805.471398146201;4870.805167793249;5304.734763261821;5372.5433338166695;5419.563815588872;5602.719864006784;5842.188921434369;5870.412958704095;5873.001589729756;5984.222051133412;4366.978165109218;4975.303457515951;5249.002689488985;5372.355317361941;5459.945400546023;2734.7438363350316;2787.1744007675006;3007.6390833100168;3025.8940937067537;3044.9038346195352;5673.659580425187;5867.589268751147;6197.326721785183;6218.978831879657;6238.376162383816;5253.816116435906;5894.941050589525;6152.877572026663;6183.258009038944;6233.75064997395;5709.74306156224;5957;6054.455099041019;6208.013435700533;6218;5173.456787037307;5778.36437991829;5989.850253743692;6040.879182416415;6074.453299202916;5119.846404607868;5549.861253468534;5677.659340439587;5907.552649600875;5924.318703348998;6166.630002199882;6225.844353891189;6244.595830605159;6245.781397651061;6266.686665666607;4539.464474490886;4579.35888975537;4735.644826638085;4750.643701722315;4756.143894099096;5443.782248710166;5782.22453222464;5938.1294892108845;5956.076808094752;6025.8192254232445;4913;5603.122953810356;5642.633228840113;5811.331696854916;5877.441643043904;4801.927971080367;4894.853154405374;5490.752916118704;5707.914381284201;5722.341930677859;5137.743112844418;5239.685618862881;5360.142377219626;5636.392708666374;5700.429957004267;5022.899542009066;5766;5782.352029671523;5782.797602083907;5867.559933005127;4346.413234213341;5260.815871444481;5490.945090549203;5615.466530679579;5673.347565030074 22:16:30 INFO - PROCESS | 1647 | |5;yui.html;684.2609981220281;686.210857513859;700.1108592088045;780.4224873593539;805.69383634219;12375.44310506027;14346.995710300283;15109.924450377746;15741.134237616927;16080.83919160808;14045.57863264101;14239.430422783102;14240.430382784676;14418.35117419716;14734.18961957092;924.2190349154987;951.3483263964172;957.0859828863419;958.9232861371108;969.4135048295785;826.5454000299811;915.1763412928384;956.4882787708577;960.6733710538416;964.6671898195146;977.4672803322171;980.8087422952527;981.4454833019357;982.0915653020962;982.3761911186413;828.883956246123;971.3637567393368;972.0522490571707;975.0298453039201;978.3982850546898;720.8630360231539;727.0729819480205;733.4242619543686;734.1777209525312;734.3427632269089;15243.61890952724;15667.138307393088;15679.529614111594;15992;16114.677706445806;13470.86529134706;13522.661933451644;13747.38136783847;13828.101173423694;13834.23911684857;11597.188196826226;11631.88368116325;11770.58802941893;11777.941110294394;12342.382880855921;3557.3063252665743;3634.4184930411006;3638.699909356229;3657.4513822926774;3676.9080772980624;121877;122786;124397;124576;124725.37637311756;10341.482925853677;10565.366078035342;10632.78734425307;10653.414062226497;10682.359058456517;21678.34964951054;21783.891080544498;21816.781832181794;21907.795071270913;22064.779352206595;7290.599017054007;7347;7458.3660388866465;7750.883736743954;7767.611619419006;4251.213525497793;4259.637930775857;4297;4345.239583072949;4477.350784136347;3409.2670075933793;3489.354469423165;3505.509228707983;3506.859725610998;3511.5610548681416;544.2679595943449;629.4460874430472;641.6567136581906;650.7884937395365;656.4649810404505;660.2967839251182;672.8143428186913;710.9004739336497;716.2372073741449;731.8426538294292;9987.850182247272;10027.147692446079;10119.443430611387;10293.125084367763;10353.63762268317;681.158768920383;695.9269276725871;718.2960698515484;725.5900416264793;726.1649103530982;4221.598948099926;4364.476262848479;4772.76136193189;4829.203181475017;4831.879203019882;89749.10250897538;94091.0590894096;101237;105542;113675;93747;95026;96261;96621.51689241649;98372.50813745885;83205.58397207975;83699.7445038325;84486;85602.14790334326;86688.133118668;7804.84390312202;9100.953390360193;9278.443293402417;9363.250939924654;9425.622975081194;52619.73690131525;52700.472995269556;53690;53703.73148134234;54805.72597136989;45471.54528454673;66307;66830;67080.3291967074;67167;10661.828665159057;11034.282771619819;11279.943600281946;11303.208775385972;11437;11969.880301197225;12009.339486328336;12048.457819398323;12223.572174973833;12497.312647804094;11264.354149946901;11284.943575282072;11681.474333655176;11748.001419879405;12076.275423474619;11093.093718541842;11304.49129789145;11474.713132171762;11477.196596238184;11568.07455403549;10148.492575371349;10244.436555989492;10335.711078964374;10400.947995259976;10789.946050269698;9461.432314061178;9682.128608424993;9790.706278811647;10056.497175141067;10095.596176152872;9450.620507846448;9757.658481953098;9769;9779.070988256104;9781.51092445389 22:16:30 INFO - PROCESS | 1647 | __end_tp_report 22:16:30 INFO - PROCESS | 1647 | __start_cc_report 22:16:30 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,428 22:16:30 INFO - PROCESS | 1647 | __end_cc_report 22:16:30 INFO - PROCESS | 1647 | __startTimestamp1489727789989__endTimestamp 22:16:30 INFO - PROCESS | 1647 | 22:16:30 INFO - PROCESS | 1647 | ------- Summary: start ------- 22:16:30 INFO - PROCESS | 1647 | Number of tests: 6 22:16:30 INFO - PROCESS | 1647 | 22:16:30 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5975.35 Median:6237.06 stddev:709.32 (11.4%) stddev-sans-first:662.34 22:16:30 INFO - PROCESS | 1647 | Values: 2667.7 3107.2 3496.9 3510.7 3513.4 6015.8 6054.0 6071.8 6091.2 6091.8 6029.8 6067.4 6100.6 6112.7 6162.6 5410.1 5482.7 5485.8 5519.9 5600.0 5536.4 5544.6 5549.2 5550.6 5577.2 5326.0 5330.8 5547.1 5558.8 5565.6 4951.6 5138.6 5419.9 5455.7 5496.5 5331.0 5434.9 5470.0 5495.1 5509.7 5790.6 6023.6 6113.9 6115.6 6130.2 6289.4 6295.0 6326.4 6327.5 6342.4 6542.1 6579.1 6580.1 6584.9 6587.6 6180.1 6204.8 6219.1 6243.0 6244.3 6440.1 6488.4 6624.6 6625.6 6740.8 5348.9 6382.1 6412.3 6431.4 6439.2 6458.0 6521.0 6545.3 6551.9 6575.3 6255.2 6303.4 6467.7 6483.6 6502.4 6240.9 6336.6 6355.9 6386.7 6422.3 4770.2 5099.5 5144.2 5147.1 5251.2 4635.3 4656.3 4659.7 4666.0 4676.2 5978.8 6153.2 6241.6 6245.2 6281.6 5342.9 6364.1 6394.5 6462.5 6536.1 6223.7 6224.4 6237.1 6245.2 6257.2 4761.3 5758.1 5775.2 5783.6 5795.0 6426.2 6487.1 6683.3 6693.3 6802.4 5569.4 6593.6 6620.3 6623.7 6626.8 6721.9 6830.0 6831.2 6838.6 6858.2 5246.4 5922.7 6203.4 6269.6 6358.5 5745.6 6308.0 6342.8 6464.0 6504.4 6325.8 6351.1 6376.6 6378.6 6427.7 6160.1 6272.9 6273.6 6291.5 6366.3 6067.4 6194.1 6249.8 6269.8 6277.6 5299.4 6376.5 6378.7 6391.8 6464.3 6107.0 6140.1 6200.2 6231.5 6281.9 22:16:30 INFO - PROCESS | 1647 | 22:16:30 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:57715.90 Median:17146.99 stddev:123582.03 (720.7%) stddev-sans-first:123854.58 22:16:30 INFO - PROCESS | 1647 | Values: 702.3 752.5 823.8 959.6 968.7 37372.0 38322.8 39868.0 39980.6 40116.2 38438.6 38649.0 38701.8 38783.8 38841.0 7852.1 7948.4 10587.9 10883.3 10908.1 10533.3 10596.8 10650.1 10664.5 10668.4 10604.6 10687.2 10704.1 10711.0 10714.3 10589.1 10607.3 10639.9 10648.9 10664.8 10524.8 10574.2 10579.1 10584.0 10587.3 33669.5 33736.8 33854.5 33855.8 33904.5 3622.7 3687.3 3696.7 3706.6 3739.7 24597.8 24756.3 25202.1 25981.7 26181.2 3010.1 3039.0 3123.8 3190.8 3220.5 346205.0 362483.0 368548.0 369216.0 369740.0 44215.9 44872.6 44957.8 45002.5 45012.3 46031.0 46653.0 46703.1 46820.1 46879.0 12565.4 12579.4 12747.3 12752.4 12761.7 10586.0 10586.8 10597.2 10605.7 10620.9 9339.5 9777.6 12005.8 12126.3 12132.8 4294.5 5481.8 5588.2 5744.3 5756.1 922.8 926.8 981.5 983.4 1041.0 26907.5 28647.9 29288.1 29410.0 29530.4 938.5 988.7 1012.7 1057.0 1065.4 14630.3 15069.8 15128.2 15364.5 15394.7 506414.0 506898.0 507856.0 509867.0 511922.0 47229.0 49042.8 57210.7 57697.7 57705.0 1377.1 1402.7 1404.6 1415.9 1423.1 25725.1 26393.0 27055.0 27526.0 27561.9 39778.6 39943.8 40217.0 40261.6 40406.8 466496.0 507430.0 508931.0 515652.0 516857.0 29347.9 29618.8 29629.7 29879.6 29918.4 36360.3 36902.1 37381.0 37718.4 37776.4 31063.1 31170.2 31245.7 31934.7 32224.4 24873.9 30905.8 31240.0 31569.5 31877.7 28201.3 28997.8 29122.9 29271.0 29323.7 26895.9 27339.0 28007.2 28490.0 28804.7 20484.7 21634.4 21658.7 21834.3 22009.6 22:16:30 INFO - PROCESS | 1647 | 22:16:30 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:45141.22 Median:21408.70 stddev:133583.72 (624.0%) stddev-sans-first:133916.18 22:16:30 INFO - PROCESS | 1647 | Values: 380.0 413.6 413.7 422.5 449.5 19748.8 20084.9 21161.2 21252.4 21392.0 15938.9 19072.6 19138.0 21406.9 21410.5 5679.7 6243.2 6481.3 6510.9 6521.2 5581.7 6618.1 6620.7 6621.5 6750.8 6050.8 6473.6 6686.7 6689.3 6689.7 6667.5 7137.3 7540.4 7650.8 7667.8 7190.7 7346.2 7350.3 7423.1 7437.1 25431.5 25554.2 26092.9 26192.3 26646.9 3143.7 3237.9 3325.0 3332.9 3350.8 12248.8 13710.6 15837.4 16396.5 16521.6 1676.3 1738.9 1996.1 2001.8 2003.6 158327.0 159346.0 163078.0 164182.0 168532.3 20399.7 20537.3 20641.9 20759.7 20970.4 31130.8 31459.9 31464.7 31541.5 31632.5 9807.3 9818.2 9927.3 9963.1 10101.0 8161.7 8168.1 8180.3 8330.8 8331.5 7644.8 9407.5 9504.2 9568.0 9629.3 4033.2 4084.5 4127.2 4130.8 4139.2 984.0 995.7 998.9 1014.6 1022.8 14457.0 19997.7 20934.5 21009.7 21213.4 983.7 984.2 1012.3 1013.8 1021.5 10684.0 10896.7 10977.6 11010.9 11024.1 768147.0 782082.0 793989.0 804638.0 846559.0 144195.0 164915.0 172924.0 173987.8 178210.3 1207.6 1247.1 1281.3 1294.4 1354.7 18520.7 19101.6 20201.9 20792.6 20838.8 53081.5 63262.1 65944.0 66178.7 66509.3 94042.1 94177.0 94320.5 95140.0 96613.5 11435.8 11720.2 11882.3 12267.4 12288.3 12138.7 12715.2 12848.7 13073.4 13449.4 11005.0 11580.6 11608.4 11637.9 11686.5 9014.5 9383.8 10690.6 10864.6 11135.2 9645.4 11563.8 11672.5 11853.3 12011.0 9834.6 11592.4 11906.6 12074.1 12208.0 11340.6 11424.5 11537.7 11684.9 11833.6 22:16:30 INFO - PROCESS | 1647 | 22:16:30 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:6802.57 Median:2449.59 stddev:4300.69 (175.6%) stddev-sans-first:4293.97 22:16:30 INFO - PROCESS | 1647 | Values: 1453.2 1523.1 1557.9 1585.7 1605.8 8903.4 9534.5 10637.6 11235.2 11333.8 10570.6 10822.0 10858.9 10888.9 10902.7 2382.3 2447.4 2451.8 2456.8 2460.3 2394.1 2428.8 2428.9 2430.7 2431.9 2399.0 2407.1 2419.3 2426.5 2433.0 2329.6 2367.5 2370.2 2372.4 2372.6 2327.5 2336.8 2389.4 2395.7 2397.1 11043.7 11126.2 11142.8 11159.5 11181.0 5811.7 5957.9 5977.8 5982.9 5983.8 3060.4 3537.8 3562.4 3681.0 3690.5 1155.2 1176.6 1193.9 1214.0 1231.5 15418.2 15538.2 15550.8 15553.7 15568.6 4685.6 4902.0 4934.9 4961.2 5038.6 12698.2 12777.4 12778.9 12780.0 12791.7 5319.2 5634.8 5898.8 5991.2 6000.9 4360.1 4999.6 5167.9 5237.0 5346.0 4112.9 4140.4 4186.9 4195.3 4199.1 1005.5 1161.1 1163.2 1163.5 1172.6 808.9 859.3 889.6 898.8 921.6 5664.4 6374.9 6806.6 6852.8 6884.1 830.5 944.8 978.2 1006.8 1059.5 4170.3 4288.4 4417.6 4434.8 4532.8 13526.7 14014.5 14086.4 14097.2 14123.5 13639.9 13713.0 13903.4 13999.9 14032.8 9922.3 10361.4 10373.1 10448.3 10473.5 6411.1 6462.4 6545.0 6582.0 6618.8 12278.8 12472.1 12487.3 12489.0 12596.5 12485.4 12651.1 12851.2 12992.7 13009.5 7465.7 7715.3 8308.5 8372.2 8632.0 10413.6 10415.4 10487.9 10527.4 10531.1 9217.4 9237.1 9372.0 9375.3 9386.1 8937.9 9040.1 9102.3 9110.6 9131.6 7876.8 7919.6 7965.2 8006.8 8009.0 5602.9 5670.9 5690.1 5857.6 5878.9 4942.8 6017.2 6017.2 6054.0 6125.4 22:16:30 INFO - PROCESS | 1647 | 22:16:30 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4802.72 Median:5130.99 stddev:1357.25 (26.5%) stddev-sans-first:1319.69 22:16:30 INFO - PROCESS | 1647 | Values: 372.3 373.7 381.2 393.6 395.6 5115.5 5267.8 5352.0 5387.2 5546.8 4318.2 4393.8 4582.5 5269.7 5273.5 3503.6 3816.8 4229.9 4407.3 4417.8 4086.9 4286.8 4404.7 4426.9 4469.4 4447.3 4447.7 4448.9 4463.5 4478.3 4172.6 4299.5 4376.8 4377.8 4442.9 4009.6 4033.3 4034.0 4040.0 4072.4 4327.8 4735.4 4843.1 5133.8 5189.6 4269.7 4811.3 5086.6 5128.2 5150.3 5480.5 5570.9 5698.9 5707.3 5764.6 4532.4 4550.5 4559.0 4579.5 4587.8 5799.7 6045.0 6046.3 6098.9 6140.6 4109.4 4588.7 4692.8 4696.9 4708.4 4660.1 4794.5 4796.7 4830.7 4844.8 4657.5 4889.9 5047.9 5066.7 5084.0 5544.2 5686.4 5687.3 5738.3 5757.3 1592.6 1614.7 1616.3 1619.4 1633.4 1166.0 1189.2 1206.8 1211.2 1230.3 4805.5 4870.8 5304.7 5372.5 5419.6 5602.7 5842.2 5870.4 5873.0 5984.2 4367.0 4975.3 5249.0 5372.4 5459.9 2734.7 2787.2 3007.6 3025.9 3044.9 5673.7 5867.6 6197.3 6219.0 6238.4 5253.8 5894.9 6152.9 6183.3 6233.8 5709.7 5957.0 6054.5 6208.0 6218.0 5173.5 5778.4 5989.9 6040.9 6074.5 5119.8 5549.9 5677.7 5907.6 5924.3 6166.6 6225.8 6244.6 6245.8 6266.7 4539.5 4579.4 4735.6 4750.6 4756.1 5443.8 5782.2 5938.1 5956.1 6025.8 4913.0 5603.1 5642.6 5811.3 5877.4 4801.9 4894.9 5490.8 5707.9 5722.3 5137.7 5239.7 5360.1 5636.4 5700.4 5022.9 5766.0 5782.4 5782.8 5867.6 4346.4 5260.8 5490.9 5615.5 5673.3 22:16:30 INFO - PROCESS | 1647 | 22:16:30 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20841.62 Median:24974.45 stddev:31663.06 (126.8%) stddev-sans-first:31715.71 22:16:30 INFO - PROCESS | 1647 | Values: 684.3 686.2 700.1 780.4 805.7 12375.4 14347.0 15109.9 15741.1 16080.8 14045.6 14239.4 14240.4 14418.4 14734.2 924.2 951.3 957.1 958.9 969.4 826.5 915.2 956.5 960.7 964.7 977.5 980.8 981.4 982.1 982.4 828.9 971.4 972.1 975.0 978.4 720.9 727.1 733.4 734.2 734.3 15243.6 15667.1 15679.5 15992.0 16114.7 13470.9 13522.7 13747.4 13828.1 13834.2 11597.2 11631.9 11770.6 11777.9 12342.4 3557.3 3634.4 3638.7 3657.5 3676.9 121877.0 122786.0 124397.0 124576.0 124725.4 10341.5 10565.4 10632.8 10653.4 10682.4 21678.3 21783.9 21816.8 21907.8 22064.8 7290.6 7347.0 7458.4 7750.9 7767.6 4251.2 4259.6 4297.0 4345.2 4477.4 3409.3 3489.4 3505.5 3506.9 3511.6 544.3 629.4 641.7 650.8 656.5 660.3 672.8 710.9 716.2 731.8 9987.9 10027.1 10119.4 10293.1 10353.6 681.2 695.9 718.3 725.6 726.2 4221.6 4364.5 4772.8 4829.2 4831.9 89749.1 94091.1 101237.0 105542.0 113675.0 93747.0 95026.0 96261.0 96621.5 98372.5 83205.6 83699.7 84486.0 85602.1 86688.1 7804.8 9101.0 9278.4 9363.3 9425.6 52619.7 52700.5 53690.0 53703.7 54805.7 45471.5 66307.0 66830.0 67080.3 67167.0 10661.8 11034.3 11279.9 11303.2 11437.0 11969.9 12009.3 12048.5 12223.6 12497.3 11264.4 11284.9 11681.5 11748.0 12076.3 11093.1 11304.5 11474.7 11477.2 11568.1 10148.5 10244.4 10335.7 10400.9 10789.9 9461.4 9682.1 9790.7 10056.5 10095.6 9450.6 9757.7 9769.0 9779.1 9781.5 22:16:30 INFO - PROCESS | 1647 | -------- Summary: end -------- 22:16:30 INFO - PROCESS | 1647 | 22:16:30 INFO - TEST-INFO | 1647: exit 0 22:16:30 INFO - TEST-OK | dromaeo_css | took 1116558ms 22:16:30 INFO - TEST-START | kraken 22:16:30 INFO - operating with platform_type : mac_ 22:16:30 INFO - Initialising browser for kraken test... 22:16:30 INFO - TEST-INFO | started process 1769 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpxmsoTQ/profile http://localhost:49235/getInfo.html) 22:16:33 INFO - PROCESS | 1769 | 2017-03-16 22:16:33.486 plugin-container[1771:10295] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 22:16:33 INFO - PROCESS | 1769 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:16:33 INFO - PROCESS | 1769 | 2017-03-16 22:16:33.488 plugin-container[1771:10295] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 22:16:33 INFO - PROCESS | 1769 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:16:33 INFO - PROCESS | 1769 | 2017-03-16 22:16:33.488 plugin-container[1771:10295] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:16:33 INFO - PROCESS | 1769 | __metrics Screen width/height:1600/1200 22:16:33 INFO - PROCESS | 1769 | colorDepth:24 22:16:33 INFO - PROCESS | 1769 | Browser inner width/height: 1024/689 22:16:33 INFO - PROCESS | 1769 | __metrics 22:16:33 ERROR - PROCESS | 1769 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 22:16:34 INFO - TEST-INFO | 1769: exit 0 22:16:34 INFO - Browser initialized. 22:16:34 INFO - Running cycle 1/1 for kraken test... 22:16:34 INFO - TEST-INFO | started process 1772 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpxmsoTQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:16:36 INFO - PROCESS | 1772 | 2017-03-16 22:16:36.765 plugin-container[1773:10417] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 22:16:36 INFO - PROCESS | 1772 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:16:36 INFO - PROCESS | 1772 | 2017-03-16 22:16:36.766 plugin-container[1773:10417] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4427, name = 'com.apple.CFPasteboardClient' 22:16:36 INFO - PROCESS | 1772 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:16:36 INFO - PROCESS | 1772 | 2017-03-16 22:16:36.766 plugin-container[1773:10417] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:16:46 INFO - PROCESS | 1772 | RSS: Main: 220053504 22:16:46 INFO - PROCESS | 1772 | 22:16:49 INFO - PROCESS | 1772 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 22:16:50 INFO - PROCESS | 1772 | RSS: Main: 234815488 22:16:50 INFO - PROCESS | 1772 | 22:16:53 INFO - PROCESS | 1772 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 22:16:53 INFO - PROCESS | 1772 | RSS: Main: 243036160 22:16:53 INFO - PROCESS | 1772 | 22:16:57 INFO - PROCESS | 1772 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 22:16:58 INFO - PROCESS | 1772 | RSS: Main: 244658176 22:16:58 INFO - PROCESS | 1772 | 22:17:00 INFO - PROCESS | 1772 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 22:17:00 INFO - PROCESS | 1772 | RSS: Main: 246599680 22:17:00 INFO - PROCESS | 1772 | 22:17:02 INFO - PROCESS | 1772 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:17:03 INFO - PROCESS | 1772 | RSS: Main: 246763520 22:17:03 INFO - PROCESS | 1772 | 22:17:06 INFO - PROCESS | 1772 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 22:17:06 INFO - PROCESS | 1772 | RSS: Main: 250687488 22:17:06 INFO - PROCESS | 1772 | 22:17:09 INFO - PROCESS | 1772 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 22:17:10 INFO - PROCESS | 1772 | RSS: Main: 250298368 22:17:10 INFO - PROCESS | 1772 | 22:17:13 INFO - PROCESS | 1772 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 22:17:14 INFO - PROCESS | 1772 | RSS: Main: 251559936 22:17:14 INFO - PROCESS | 1772 | 22:17:15 INFO - PROCESS | 1772 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:17:16 INFO - PROCESS | 1772 | RSS: Main: 251281408 22:17:16 INFO - PROCESS | 1772 | 22:17:18 INFO - PROCESS | 1772 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:17:18 INFO - PROCESS | 1772 | RSS: Main: 251265024 22:17:18 INFO - PROCESS | 1772 | 22:17:21 INFO - PROCESS | 1772 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:17:21 INFO - PROCESS | 1772 | RSS: Main: 251056128 22:17:21 INFO - PROCESS | 1772 | 22:17:24 INFO - PROCESS | 1772 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:17:25 INFO - PROCESS | 1772 | RSS: Main: 251461632 22:17:25 INFO - PROCESS | 1772 | 22:17:28 INFO - PROCESS | 1772 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:17:29 INFO - PROCESS | 1772 | RSS: Main: 251162624 22:17:29 INFO - PROCESS | 1772 | 22:17:30 INFO - PROCESS | 1772 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 22:17:31 INFO - PROCESS | 1772 | RSS: Main: 253063168 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | __start_tp_report 22:17:31 INFO - PROCESS | 1772 | _x_x_mozilla_page_load 22:17:31 INFO - PROCESS | 1772 | _x_x_mozilla_page_load_details 22:17:31 INFO - PROCESS | 1772 | |i|pagename|runs| 22:17:31 INFO - PROCESS | 1772 | |0;ai-astar;108;122;119;118;122;121;122;102;178;179 22:17:31 INFO - PROCESS | 1772 | |1;audio-beat-detection;147;116;99;173;102;145;143;142;133;128 22:17:31 INFO - PROCESS | 1772 | |2;audio-dft;195;196;198;200;196;230;280;189;273;274 22:17:31 INFO - PROCESS | 1772 | |3;audio-fft;125;83;91;84;60;65;60;63;73;62 22:17:31 INFO - PROCESS | 1772 | |4;audio-oscillator;91;71;85;73;72;97;116;71;70;70 22:17:31 INFO - PROCESS | 1772 | |5;imaging-gaussian-blur;90;90;92;89;90;89;90;96;89;90 22:17:31 INFO - PROCESS | 1772 | |6;imaging-darkroom;105;128;97;96;97;96;97;96;96;125 22:17:31 INFO - PROCESS | 1772 | |7;imaging-desaturate;140;89;90;90;91;91;91;90;91;96 22:17:31 INFO - PROCESS | 1772 | |8;json-parse-financial;65;65;68;73;71;69;64;73;70;70 22:17:31 INFO - PROCESS | 1772 | |9;json-stringify-tinderbox;58;59;65;61;67;63;64;72;85;58 22:17:31 INFO - PROCESS | 1772 | |10;stanford-crypto-aes;84;86;88;87;88;87;85;96;87;92 22:17:31 INFO - PROCESS | 1772 | |11;stanford-crypto-ccm;142;165;139;149;155;172;159;153;180;156 22:17:31 INFO - PROCESS | 1772 | |12;stanford-crypto-pbkdf2;171;173;168;173;175;168;147;197;160;229 22:17:31 INFO - PROCESS | 1772 | |13;stanford-crypto-sha256-iterative;67;67;68;66;69;66;67;65;67;66 22:17:31 INFO - PROCESS | 1772 | __end_tp_report 22:17:31 INFO - PROCESS | 1772 | __start_cc_report 22:17:31 INFO - PROCESS | 1772 | _x_x_mozilla_cycle_collect,1010 22:17:31 INFO - PROCESS | 1772 | __end_cc_report 22:17:31 INFO - PROCESS | 1772 | __startTimestamp1489727851045__endTimestamp 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | ------- Summary: start ------- 22:17:31 INFO - PROCESS | 1772 | Number of tests: 14 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | [#0] ai-astar Cycles:10 Average:129.10 Median:122.00 stddev:26.88 (22.0%) stddev-sans-first:27.40 22:17:31 INFO - PROCESS | 1772 | Values: 108.0 122.0 119.0 118.0 122.0 121.0 122.0 102.0 178.0 179.0 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | [#1] audio-beat-detection Cycles:10 Average:132.80 Median:144.00 stddev:22.49 (15.6%) stddev-sans-first:23.26 22:17:31 INFO - PROCESS | 1772 | Values: 147.0 116.0 99.0 173.0 102.0 145.0 143.0 142.0 133.0 128.0 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | [#2] audio-dft Cycles:10 Average:223.10 Median:215.00 stddev:37.93 (17.6%) stddev-sans-first:38.85 22:17:31 INFO - PROCESS | 1772 | Values: 195.0 196.0 198.0 200.0 196.0 230.0 280.0 189.0 273.0 274.0 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | [#3] audio-fft Cycles:10 Average:76.60 Median:69.00 stddev:20.39 (29.6%) stddev-sans-first:11.94 22:17:31 INFO - PROCESS | 1772 | Values: 125.0 83.0 91.0 84.0 60.0 65.0 60.0 63.0 73.0 62.0 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | [#4] audio-oscillator Cycles:10 Average:81.60 Median:72.50 stddev:15.56 (21.5%) stddev-sans-first:16.13 22:17:31 INFO - PROCESS | 1772 | Values: 91.0 71.0 85.0 73.0 72.0 97.0 116.0 71.0 70.0 70.0 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:90.00 stddev:2.12 (2.4%) stddev-sans-first:2.24 22:17:31 INFO - PROCESS | 1772 | Values: 90.0 90.0 92.0 89.0 90.0 89.0 90.0 96.0 89.0 90.0 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | [#6] imaging-darkroom Cycles:10 Average:103.30 Median:96.00 stddev:12.54 (13.1%) stddev-sans-first:13.29 22:17:31 INFO - PROCESS | 1772 | Values: 105.0 128.0 97.0 96.0 97.0 96.0 97.0 96.0 96.0 125.0 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:91.00 stddev:15.61 (17.2%) stddev-sans-first:2.00 22:17:31 INFO - PROCESS | 1772 | Values: 140.0 89.0 90.0 90.0 91.0 91.0 91.0 90.0 91.0 96.0 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | [#8] json-parse-financial Cycles:10 Average:68.80 Median:70.00 stddev:3.26 (4.7%) stddev-sans-first:3.15 22:17:31 INFO - PROCESS | 1772 | Values: 65.0 65.0 68.0 73.0 71.0 69.0 64.0 73.0 70.0 70.0 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:64.50 stddev:8.22 (12.7%) stddev-sans-first:8.29 22:17:31 INFO - PROCESS | 1772 | Values: 58.0 59.0 65.0 61.0 67.0 63.0 64.0 72.0 85.0 58.0 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:87.50 stddev:3.53 (4.0%) stddev-sans-first:3.43 22:17:31 INFO - PROCESS | 1772 | Values: 84.0 86.0 88.0 87.0 88.0 87.0 85.0 96.0 87.0 92.0 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | [#11] stanford-crypto-ccm Cycles:10 Average:157.00 Median:157.50 stddev:12.72 (8.1%) stddev-sans-first:12.28 22:17:31 INFO - PROCESS | 1772 | Values: 142.0 165.0 139.0 149.0 155.0 172.0 159.0 153.0 180.0 156.0 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.10 Median:173.00 stddev:22.41 (13.0%) stddev-sans-first:23.69 22:17:31 INFO - PROCESS | 1772 | Values: 171.0 173.0 168.0 173.0 175.0 168.0 147.0 197.0 160.0 229.0 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - PROCESS | 1772 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 22:17:31 INFO - PROCESS | 1772 | Values: 67.0 67.0 68.0 66.0 69.0 66.0 67.0 65.0 67.0 66.0 22:17:31 INFO - PROCESS | 1772 | -------- Summary: end -------- 22:17:31 INFO - PROCESS | 1772 | 22:17:31 INFO - TEST-INFO | 1772: exit 0 22:17:31 INFO - TEST-OK | kraken | took 61321ms 22:17:31 INFO - SUITE-END | took 1177s 22:17:32 INFO - Completed test suite (00:19:38) 22:17:32 INFO - javascript benchmark 22:17:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7698.458137318944, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2667.7065522792477, 3107.238726512005, 3496.91257718557, 3510.719142468603, 3513.4378499440095, 6015.789447369343, 6053.969730151343, 6071.817845464638, 6091.177691011712, 6091.756329746805, 6029.849253768658, 6067.393260673931, 6100.572959892809, 6112.6638034908065, 6162.568620196588, 5410.08028635132, 5482.725863706799, 5485.807996720116, 5519.9448005520035, 5600.0, 5536.390996990328, 5544.584156188282, 5549.19536667183, 5550.639208451458, 5577.247071645337, 5326.041312563737, 5330.786768529254, 5547.084731019377, 5558.805441809537, 5565.582581306418, 4951.63829946764, 5138.614603904684, 5419.864503387407, 5455.672659640434, 5496.450354964471, 5331.040412725708, 5434.91847622286, 5470.0, 5495.120780675073, 5509.724513774336, 5790.565707571906, 6023.578349515536, 6113.877722445526, 6115.571909966304, 6130.2030736004035, 6289.371062893674, 6294.968525157345, 6326.398992095744, 6327.493800495995, 6342.39747224013, 6542.084108224853, 6579.078928949951, 6580.14458120446, 6584.93415065848, 6587.637679927602, 6180.072989051589, 6204.751809927642, 6219.067139929001, 6243.0, 6244.344343843922, 6440.0661904023655, 6488.383603557749, 6624.6025238485845, 6625.569337993015, 6740.764073257511, 5348.893022139536, 6382.074599183093, 6412.326705695835, 6431.42117209444, 6439.195100612424, 6458.0, 6520.967395162994, 6545.312742162099, 6551.901721474182, 6575.342465753386, 6255.155554000243, 6303.369663033752, 6467.741290348334, 6483.643399613066, 6502.382273683992, 6240.875182496325, 6336.619802811846, 6355.904661430082, 6386.712597933104, 6422.2614399344675, 4770.212914869077, 5099.515546023122, 5144.176931690987, 5147.124988751922, 5251.1598144297495, 4635.258358662598, 4656.301554766812, 4659.6505262105475, 4665.976670116627, 4676.228422310349, 5978.8206353809455, 6153.2000839891425, 6241.594296370812, 6245.156903818017, 6281.623102613858, 5342.9465705342445, 6364.109024736541, 6394.488440924715, 6462.547621666438, 6536.117624120683, 6223.719932603044, 6224.408681175281, 6237.064440333987, 6245.18812554365, 6257.186565746424, 4761.309610106515, 5758.078707406795, 5775.220345253421, 5783.59514833966, 5795.014847475939, 6426.164598602152, 6487.0593763903025, 6683.264840867598, 6693.263740988585, 6802.421794147553, 5569.443055694398, 6593.637349945799, 6620.304867988792, 6623.735050597922, 6626.83432914181, 6721.93278067213, 6829.965850170717, 6831.180258369028, 6838.589684618939, 6858.245592984668, 5246.396664383645, 5922.73347699346, 6203.379662033941, 6269.62382257066, 6358.523110766803, 5745.569082318759, 6308.0, 6342.809715708536, 6463.967680161569, 6504.414602685781, 6325.778597749057, 6351.142595749515, 6376.553641245254, 6378.585391949508, 6427.742890284337, 6160.137580738789, 6272.874542509216, 6273.592216505913, 6291.4568848398985, 6366.267879193952, 6067.393260673897, 6194.0708893664505, 6249.78125765596, 6269.843253918506, 6277.591956522811, 5299.364076310867, 6376.521760868046, 6378.6810659467765, 6391.808245752635, 6464.321246269262, 6107.037103281039, 6140.140380346845, 6200.193974783249, 6231.501479881508, 6281.905771413524], "value": 5927.9016888878605, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.3012102957557, 752.5371896283783, 823.8352329534091, 959.6257459590756, 968.7045451137409, 37372.0, 38322.808385958066, 39868.0, 39980.60019399805, 40116.19767604646, 38438.61561384385, 38649.03377415566, 38701.806490967574, 38783.80608096955, 38841.0, 7852.097008843984, 7948.4436089473475, 10587.894121058805, 10883.292585981886, 10908.127349812035, 10533.26267161299, 10596.841047384296, 10650.147988160967, 10664.520096595634, 10668.413237271947, 10604.628837990673, 10687.198460115522, 10704.090152337061, 10711.0, 10714.250002499828, 10589.099926506256, 10607.3105248159, 10639.946800265989, 10648.946755266174, 10664.786704265949, 10524.842127368096, 10574.154067674644, 10579.100776434012, 10584.0, 10587.311824731369, 33669.494957575655, 33736.83131584351, 33854.49218261728, 33855.8150464735, 33904.49143262853, 3622.710183185339, 3687.3362794696945, 3696.7227457940494, 3706.592274849764, 3739.66343029126, 24597.754022459725, 24756.25731228066, 25202.11792587269, 25981.74018259812, 26181.214563563124, 3010.1186758276076, 3038.9696103039128, 3123.7657175711684, 3190.840457977115, 3220.516922461626, 346205.0, 362483.0, 368548.0, 369216.0, 369740.0, 44215.89460263519, 44872.55127448749, 44957.775211123735, 45002.54997449984, 45012.3248151278, 46031.0, 46653.0, 46703.06593868104, 46820.06359872784, 46879.0, 12565.434555445026, 12579.433925473375, 12747.298898560668, 12752.426140823685, 12761.68095797594, 10586.0, 10586.788264234827, 10597.20520960931, 10605.681829544972, 10620.89379106199, 9339.533023348806, 9777.608895644236, 12005.819912701316, 12126.333051682066, 12132.818007729891, 4294.506131794884, 5481.75332110056, 5588.2455868458055, 5744.253247077937, 5756.0502517084215, 922.7831459606987, 926.7636752628036, 981.4552923127733, 983.4148681534475, 1040.959040959041, 26907.461850763266, 28647.85408583675, 29288.121356359345, 29409.97065102754, 29530.409391812045, 938.5119737736339, 988.6885631026204, 1012.6860673191187, 1056.9947150264354, 1065.4086981725218, 14630.268486575842, 15069.773953390708, 15128.243587820565, 15364.462243821412, 15394.692106157814, 506414.0, 506898.0, 507856.0, 509867.0, 511922.0, 47229.0, 49042.75478622656, 57210.71394643, 57697.711511442176, 57705.0, 1377.0635967541966, 1402.726468338664, 1404.557564367221, 1415.916823629924, 1423.1034448297507, 25725.070619703223, 26393.0, 27055.0, 27526.0, 27561.862190688917, 39778.60221397749, 39943.80028099841, 40216.994575135854, 40261.59738402579, 40406.79796600998, 466496.0, 507430.0, 508931.0, 515652.0, 516857.0, 29347.85326073356, 29618.815247389863, 29629.703702962695, 29879.55180672335, 29918.401631966804, 36360.27279454343, 36902.07744806401, 37381.0, 37718.43422348722, 37776.43335349917, 31063.0681079568, 31170.22074448157, 31245.687543125186, 31934.68065319317, 32224.35551289008, 24873.875630621733, 30905.8454707734, 31240.0, 31569.52645710362, 31877.681223187472, 28201.294967625974, 28997.806142800495, 29122.854385727936, 29270.975515856844, 29323.7067629321, 26895.865520672272, 27339.0, 28007.159785206477, 28490.0, 28804.694349246565, 20484.692729609367, 21634.350969470943, 21658.67511987353, 21834.344969650934, 22009.559808803413], "value": 17993.05032662399, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.9867004654837, 413.6050072181067, 413.6959334888856, 422.48034917052024, 449.5302408982613, 19748.802511974893, 20084.8953307568, 21161.15355385783, 21252.36242912711, 21392.037358318874, 15938.920305398486, 19072.618547629037, 19138.043097845122, 21406.929653517338, 21410.46473838155, 5679.7160141992945, 6243.157173781549, 6481.254655714606, 6510.869782604345, 6521.184851893513, 5581.748821303031, 6618.139641846553, 6620.735170593194, 6621.470282377399, 6750.8312292192595, 6050.757969681229, 6473.579217350881, 6686.732530698766, 6689.2976237495095, 6689.73241070359, 6667.533272670915, 7137.250588688191, 7540.359069479101, 7650.7704768856465, 7667.846643067108, 7190.712371505134, 7346.155192152917, 7350.264973502661, 7423.109226892753, 7437.144728356254, 25431.491370172494, 25554.23337299884, 26092.86953565239, 26192.34519137037, 26646.866765666047, 3143.654198038214, 3237.8866739664, 3324.983375083133, 3332.8833490827706, 3350.8177412673576, 12248.836360545734, 13710.588682339545, 15837.445689400816, 16396.50810475676, 16521.586960325967, 1676.2959556986095, 1738.8695847811464, 1996.0818023709112, 2001.7917579135858, 2003.5692326149947, 158327.0, 159346.0, 163078.0, 164182.0, 168532.3146768529, 20399.694004589943, 20537.281195158397, 20641.864697441484, 20759.68860467094, 20970.37870266217, 31130.844345778125, 31459.898903538266, 31464.685353146633, 31541.52687709686, 31632.525512117336, 9807.26445516589, 9818.165455936325, 9927.305088643725, 9963.053509916546, 10101.040401161878, 8161.67353305873, 8168.06067302256, 8180.345572354197, 8330.750077497685, 8331.458455200393, 7644.827396630336, 9407.529623518796, 9504.192143667728, 9568.0, 9629.277804164716, 4033.1933613276874, 4084.5302790179508, 4127.174565086934, 4130.772807495617, 4139.19285739278, 983.9803203936025, 995.6913356859396, 998.9400635961865, 1014.5637375928421, 1022.7801022779989, 14457.0, 19997.700034499496, 20934.47663808417, 21009.68485472719, 21213.363599092052, 983.685220729374, 984.2224642532462, 1012.336919317848, 1013.8124446977333, 1021.5249908792299, 10684.038436540748, 10896.727581810519, 10977.560897564168, 11010.889891100986, 11024.118070554336, 768147.0, 782082.0, 793989.0, 804638.0, 846559.0, 144195.0, 164915.0, 172924.0, 173987.8208525391, 178210.3268450974, 1207.619599826052, 1247.1270110922392, 1281.2568710148091, 1294.4433893425917, 1354.695193581456, 18520.722189166903, 19101.617967640846, 20201.898990504953, 20792.58414831725, 20838.791612084293, 53081.46918530765, 63262.102136935966, 65944.0, 66178.66910665415, 66509.33490665031, 94042.05957940333, 94177.0, 94320.52839735757, 95140.0, 96613.51693241489, 11435.771284574428, 11720.179587428798, 11882.28706277626, 12267.447964841425, 12288.262704237774, 12138.696532586757, 12715.237085774883, 12848.743025139633, 13073.411696473491, 13449.394777234853, 11005.0, 11580.594679186188, 11608.401395567402, 11637.941810290895, 11686.474108665301, 9014.459132452073, 9383.812323753624, 10690.57237710514, 10864.619738309122, 11135.164862635496, 9645.42127472354, 11563.768724625628, 11672.47473863695, 11853.34806585646, 12011.0, 9834.557444915139, 11592.362420066976, 11906.583269585522, 12074.094442916641, 12208.0, 11340.586943501829, 11424.543018279175, 11537.653870383901, 11684.941575292069, 11833.585824496102], "value": 11615.527558083018, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1453.16443045249, 1523.12420358294, 1557.883158763093, 1585.6511567455157, 1605.7591361295808, 8903.42127761695, 9534.475603841785, 10637.57449702012, 11235.21353505255, 11333.829992550109, 10570.630027949024, 10822.0, 10858.945705271462, 10888.891111088888, 10902.672919812398, 2382.3091303522, 2447.363685441789, 2451.816113791464, 2456.8403053801535, 2460.2988148377717, 2394.0663141374876, 2428.8299819012677, 2428.9149879754214, 2430.6718592989987, 2431.927042188728, 2398.988005059981, 2407.0973384980634, 2419.2863105383867, 2426.4904370082304, 2433.0024689877155, 2329.580675478413, 2367.532130079353, 2370.158593699229, 2372.3713215997773, 2372.5847976604114, 2327.49958758866, 2336.778006089419, 2389.426537630956, 2395.7125144982715, 2397.11306816479, 11043.668689939315, 11126.165537584713, 11142.832857507063, 11159.497822598003, 11181.0, 5811.709414529257, 5957.910631340534, 5977.790777322816, 5982.8504287392725, 5983.820485385445, 3060.3879224155257, 3537.8407971641336, 3562.4300111982216, 3680.9509289852263, 3690.538682664667, 1155.2029099921112, 1176.6175992802373, 1193.928364298145, 1214.0409076829383, 1231.5381731850557, 15418.152001639904, 15538.223088845512, 15550.844491555054, 15553.688926221525, 15568.61078473036, 4685.570900875266, 4901.975490122527, 4934.8766280843265, 4961.206207006861, 5038.571721403649, 12698.238105713686, 12777.361131943366, 12778.936105319413, 12780.0, 12791.680207994874, 5319.175527793198, 5634.830955071355, 5898.8230353089475, 5991.191189189491, 6000.909986350208, 4360.127974405132, 4999.600031997432, 5167.870803229949, 5237.0, 5345.9732701337025, 4112.896841105234, 4140.420341152241, 4186.91626167475, 4195.286801243796, 4199.0552125772065, 1005.4620777883944, 1161.1117495116243, 1163.2202729823384, 1163.4764356039816, 1172.6013155527162, 808.9281701745211, 859.3297228162044, 889.6130183370125, 898.8112496375834, 921.5576523268868, 5664.433556644302, 6374.948133558004, 6806.557573757789, 6852.794416167523, 6884.139482564679, 830.543201239316, 944.8441007233866, 978.2272005115879, 1006.8389057750725, 1059.4914441068331, 4170.332746760504, 4288.442502474721, 4417.624501917373, 4434.778261086933, 4532.818687252539, 13526.661833454242, 14014.509492167725, 14086.366113524917, 14097.154170749787, 14123.505677301451, 13639.863601364057, 13713.0, 13903.4438622456, 13999.860001400059, 14032.789508157386, 9922.305438619227, 10361.378317300987, 10373.118284945713, 10448.32085914413, 10473.528691208914, 6411.070394792636, 6462.418382345423, 6545.0, 6582.045603387578, 6618.801435956929, 12278.75442491163, 12472.064595155218, 12487.313197773847, 12489.0, 12596.496140154657, 12485.375731213584, 12651.051171162384, 12851.16467429614, 12992.675183120498, 13009.544665937025, 7465.701371945279, 7715.305622494003, 8308.5430301334, 8372.246497815226, 8632.0, 10413.635522756667, 10415.42715150674, 10487.895121048692, 10527.368357898551, 10531.05220530156, 9217.44695318283, 9237.12247336502, 9371.953140234255, 9375.343725939392, 9386.108319709616, 8937.910620893708, 9040.141186587263, 9102.271818254392, 9110.635574576943, 9131.634734610807, 7876.763697088867, 7919.564423956737, 7965.163657815846, 8006.759797206093, 8009.0, 5602.915956260578, 5670.943290567042, 5690.118031705092, 5857.560682948882, 5878.853028674317, 4942.827001054946, 6017.18767966319, 6017.217761690952, 6054.0, 6125.418085281891], "value": 5170.251165727091, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.26849241240956, 373.701039168665, 381.1595432072279, 393.6063936063936, 395.60044355201245, 5115.462876397975, 5267.841964741059, 5351.983123206588, 5387.16498942664, 5546.750396232169, 4318.201132790436, 4393.7803109844535, 4582.5188355222635, 5269.707677160438, 5273.472652734734, 3503.6321186275454, 3816.751911125782, 4229.936550951738, 4407.2507673695545, 4417.845375411861, 4086.9386959195635, 4286.807093680776, 4404.7357158570585, 4426.911461770779, 4469.351943968123, 4447.266201076818, 4447.66642501812, 4448.911021779562, 4463.531329210435, 4478.283474644058, 4172.582741725833, 4299.484061912559, 4376.803043863034, 4377.78111094444, 4442.8667139985855, 4009.6190861868076, 4033.2740106780766, 4033.9915021244688, 4040.0, 4072.3891416287497, 4327.766586522857, 4735.377436261263, 4843.062774890037, 5133.768980395891, 5189.584833213334, 4269.719084274705, 4811.326414302001, 5086.601184674208, 5128.205128205137, 5150.253213284052, 5480.479354461319, 5570.857974115337, 5698.886022279573, 5707.259285917793, 5764.625299355528, 4532.41652258213, 4550.453945526525, 4558.954410455886, 4579.519150489184, 4587.761304447814, 5799.710014499258, 6045.0, 6046.304674962392, 6098.908516372258, 6140.570160088841, 4109.445224894661, 4588.701734387254, 4692.835750748707, 4696.929546056744, 4708.387909571734, 4660.067986402733, 4794.472608013116, 4796.664233503691, 4830.7101573905675, 4844.806207751651, 4657.532877143689, 4889.877753056131, 5047.899042019212, 5066.721330326867, 5083.991823229803, 5544.168374743821, 5686.3744988051285, 5687.289088863892, 5738.307199376194, 5757.309122905194, 1592.6018495376156, 1614.7093523165943, 1616.3211451190293, 1619.4372430604524, 1633.3747920818623, 1165.9941700291613, 1189.2210602055668, 1206.776746301937, 1211.2066596379386, 1230.329470438613, 4805.471398146201, 4870.805167793249, 5304.734763261821, 5372.5433338166695, 5419.563815588872, 5602.719864006784, 5842.188921434369, 5870.412958704095, 5873.001589729756, 5984.222051133412, 4366.978165109218, 4975.303457515951, 5249.002689488985, 5372.355317361941, 5459.945400546023, 2734.7438363350316, 2787.1744007675006, 3007.6390833100168, 3025.8940937067537, 3044.9038346195352, 5673.659580425187, 5867.589268751147, 6197.326721785183, 6218.978831879657, 6238.376162383816, 5253.816116435906, 5894.941050589525, 6152.877572026663, 6183.258009038944, 6233.75064997395, 5709.74306156224, 5957.0, 6054.455099041019, 6208.013435700533, 6218.0, 5173.456787037307, 5778.36437991829, 5989.850253743692, 6040.879182416415, 6074.453299202916, 5119.846404607868, 5549.861253468534, 5677.659340439587, 5907.552649600875, 5924.318703348998, 6166.630002199882, 6225.844353891189, 6244.595830605159, 6245.781397651061, 6266.686665666607, 4539.464474490886, 4579.35888975537, 4735.644826638085, 4750.643701722315, 4756.143894099096, 5443.782248710166, 5782.22453222464, 5938.1294892108845, 5956.076808094752, 6025.8192254232445, 4913.0, 5603.122953810356, 5642.633228840113, 5811.331696854916, 5877.441643043904, 4801.927971080367, 4894.853154405374, 5490.752916118704, 5707.914381284201, 5722.341930677859, 5137.743112844418, 5239.685618862881, 5360.142377219626, 5636.392708666374, 5700.429957004267, 5022.899542009066, 5766.0, 5782.352029671523, 5782.797602083907, 5867.559933005127, 4346.413234213341, 5260.815871444481, 5490.945090549203, 5615.466530679579, 5673.347565030074], "value": 4404.357999315672, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.2609981220281, 686.210857513859, 700.1108592088045, 780.4224873593539, 805.69383634219, 12375.44310506027, 14346.995710300283, 15109.924450377746, 15741.134237616927, 16080.83919160808, 14045.57863264101, 14239.430422783102, 14240.430382784676, 14418.35117419716, 14734.18961957092, 924.2190349154987, 951.3483263964172, 957.0859828863419, 958.9232861371108, 969.4135048295785, 826.5454000299811, 915.1763412928384, 956.4882787708577, 960.6733710538416, 964.6671898195146, 977.4672803322171, 980.8087422952527, 981.4454833019357, 982.0915653020962, 982.3761911186413, 828.883956246123, 971.3637567393368, 972.0522490571707, 975.0298453039201, 978.3982850546898, 720.8630360231539, 727.0729819480205, 733.4242619543686, 734.1777209525312, 734.3427632269089, 15243.61890952724, 15667.138307393088, 15679.529614111594, 15992.0, 16114.677706445806, 13470.86529134706, 13522.661933451644, 13747.38136783847, 13828.101173423694, 13834.23911684857, 11597.188196826226, 11631.88368116325, 11770.58802941893, 11777.941110294394, 12342.382880855921, 3557.3063252665743, 3634.4184930411006, 3638.699909356229, 3657.4513822926774, 3676.9080772980624, 121877.0, 122786.0, 124397.0, 124576.0, 124725.37637311756, 10341.482925853677, 10565.366078035342, 10632.78734425307, 10653.414062226497, 10682.359058456517, 21678.34964951054, 21783.891080544498, 21816.781832181794, 21907.795071270913, 22064.779352206595, 7290.599017054007, 7347.0, 7458.3660388866465, 7750.883736743954, 7767.611619419006, 4251.213525497793, 4259.637930775857, 4297.0, 4345.239583072949, 4477.350784136347, 3409.2670075933793, 3489.354469423165, 3505.509228707983, 3506.859725610998, 3511.5610548681416, 544.2679595943449, 629.4460874430472, 641.6567136581906, 650.7884937395365, 656.4649810404505, 660.2967839251182, 672.8143428186913, 710.9004739336497, 716.2372073741449, 731.8426538294292, 9987.850182247272, 10027.147692446079, 10119.443430611387, 10293.125084367763, 10353.63762268317, 681.158768920383, 695.9269276725871, 718.2960698515484, 725.5900416264793, 726.1649103530982, 4221.598948099926, 4364.476262848479, 4772.76136193189, 4829.203181475017, 4831.879203019882, 89749.10250897538, 94091.0590894096, 101237.0, 105542.0, 113675.0, 93747.0, 95026.0, 96261.0, 96621.51689241649, 98372.50813745885, 83205.58397207975, 83699.7445038325, 84486.0, 85602.14790334326, 86688.133118668, 7804.84390312202, 9100.953390360193, 9278.443293402417, 9363.250939924654, 9425.622975081194, 52619.73690131525, 52700.472995269556, 53690.0, 53703.73148134234, 54805.72597136989, 45471.54528454673, 66307.0, 66830.0, 67080.3291967074, 67167.0, 10661.828665159057, 11034.282771619819, 11279.943600281946, 11303.208775385972, 11437.0, 11969.880301197225, 12009.339486328336, 12048.457819398323, 12223.572174973833, 12497.312647804094, 11264.354149946901, 11284.943575282072, 11681.474333655176, 11748.001419879405, 12076.275423474619, 11093.093718541842, 11304.49129789145, 11474.713132171762, 11477.196596238184, 11568.07455403549, 10148.492575371349, 10244.436555989492, 10335.711078964374, 10400.947995259976, 10789.946050269698, 9461.432314061178, 9682.128608424993, 9790.706278811647, 10056.497175141067, 10095.596176152872, 9450.620507846448, 9757.658481953098, 9769.0, 9779.070988256104, 9781.51092445389], "value": 7378.1209099180915, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1554.7999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 122.0, 119.0, 118.0, 122.0, 121.0, 122.0, 102.0, 178.0, 179.0], "value": 129.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 99.0, 173.0, 102.0, 145.0, 143.0, 142.0, 133.0, 128.0], "value": 132.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 196.0, 198.0, 200.0, 196.0, 230.0, 280.0, 189.0, 273.0, 274.0], "value": 223.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 83.0, 91.0, 84.0, 60.0, 65.0, 60.0, 63.0, 73.0, 62.0], "value": 76.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 85.0, 73.0, 72.0, 97.0, 116.0, 71.0, 70.0, 70.0], "value": 81.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 92.0, 89.0, 90.0, 89.0, 90.0, 96.0, 89.0, 90.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 128.0, 97.0, 96.0, 97.0, 96.0, 97.0, 96.0, 96.0, 125.0], "value": 103.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 89.0, 90.0, 90.0, 91.0, 91.0, 91.0, 90.0, 91.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 68.0, 73.0, 71.0, 69.0, 64.0, 73.0, 70.0, 70.0], "value": 68.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 65.0, 61.0, 67.0, 63.0, 64.0, 72.0, 85.0, 58.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 88.0, 87.0, 88.0, 87.0, 85.0, 96.0, 87.0, 92.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 165.0, 139.0, 149.0, 155.0, 172.0, 159.0, 153.0, 180.0, 156.0], "value": 157.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 173.0, 168.0, 173.0, 175.0, 168.0, 147.0, 197.0, 160.0, 229.0], "value": 176.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 66.0, 69.0, 66.0, 67.0, 65.0, 67.0, 66.0], "value": 66.8, "unit": "score"}]}]} 22:17:32 INFO - Return code: 0 22:17:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:17:32 ERROR - # TBPL SUCCESS # 22:17:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:17:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:17:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:17:32 INFO - 2017-03-16 22:17:32.388 screenresolution[1783:10810] starting screenresolution argv=screenresolution get 22:17:32 INFO - 2017-03-16 22:17:32.391 screenresolution[1783:10810] Display 0: 1600x1200x32@60 22:17:32 INFO - 2017-03-16 22:17:32.397 screenresolution[1784:10813] starting screenresolution argv=screenresolution list 22:17:32 INFO - Available Modes on Display 0 22:17:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:17:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:17:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:17:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:17:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:17:32 INFO - Intel Iris: 22:17:32 INFO - Chipset Model: Intel Iris 22:17:32 INFO - Type: GPU 22:17:32 INFO - Bus: Built-In 22:17:32 INFO - VRAM (Dynamic, Max): 1536 MB 22:17:32 INFO - Vendor: Intel (0x8086) 22:17:32 INFO - Device ID: 0x0a2e 22:17:32 INFO - Revision ID: 0x0009 22:17:32 INFO - Displays: 22:17:32 INFO - Display: 22:17:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:17:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:17:32 INFO - Display Serial Number: 1600x1200 60 22:17:32 INFO - Main Display: Yes 22:17:32 INFO - Mirror: Off 22:17:32 INFO - Online: Yes 22:17:32 INFO - Rotation: Supported 22:17:32 INFO - Return code: 0 22:17:32 INFO - Running post-action listener: _resource_record_post_action 22:17:32 INFO - [mozharness: 2017-03-17 05:17:32.631960Z] Finished run-tests step (success) 22:17:32 INFO - Running post-run listener: _resource_record_post_run 22:17:32 INFO - Total resource usage - Wall time: 1198s; CPU: 55.0%; Read bytes: 38803968; Write bytes: 515428864; Read time: 394; Write time: 1649 22:17:32 INFO - TinderboxPrint: CPU usage
54.6% 22:17:32 INFO - TinderboxPrint: I/O read bytes / time
38,803,968 / 394 22:17:32 INFO - TinderboxPrint: I/O write bytes / time
515,428,864 / 1,649 22:17:32 INFO - TinderboxPrint: CPU idle
2,174.9 (45.4%) 22:17:32 INFO - TinderboxPrint: CPU system
962.0 (20.1%) 22:17:32 INFO - TinderboxPrint: CPU user
1,656.6 (34.6%) 22:17:32 INFO - TinderboxPrint: Swap in / out
247,373,824 / 0 22:17:32 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206260736; Write bytes: 202840064; Read time: 15629; Write time: 462 22:17:32 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 35392000; Write bytes: 301427200; Read time: 366; Write time: 1150 22:17:33 INFO - Running post-run listener: _upload_blobber_files 22:17:33 INFO - Blob upload gear active. 22:17:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:17:33 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 22:17:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:17:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:17:33 INFO - (blobuploader) - INFO - Open directory for files ... 22:17:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:17:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:17:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:17:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:17:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:17:34 INFO - (blobuploader) - INFO - Done attempting. 22:17:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:17:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:17:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:17:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:17:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:17:35 INFO - (blobuploader) - INFO - Done attempting. 22:17:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:17:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:17:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:17:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:17:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:17:36 INFO - (blobuploader) - INFO - Done attempting. 22:17:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:17:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:17:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:17:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:17:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:17:38 INFO - (blobuploader) - INFO - Done attempting. 22:17:38 INFO - (blobuploader) - INFO - Iteration through files over. 22:17:38 INFO - Return code: 0 22:17:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:17:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:17:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb85323257e472721157e8510a422982494f6fe8d3abc588910b91d17bf3a103d3d92939f3c9de304b09103ca45bacbecf52ea5b7ba7c1451913195eff3931cc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/523f9c41a0c55e28dd867d51e1ae92dafff4beca955ca85a8e9adc398b747739fcb2ae4a602217567521f5e14da686943892714ca85f70872c95f1eb88b340c5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23e8ca28f5b42629a1f9d43d95b671aff9da3daf4608fc913ed54544629287d102a7a8afc45232e0de21c6d55fa8262ac412ec73c3ddb357b43c3adfb9070287", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/987a09598e618e68b589b7eb6503a6d6da89a40aa45cf1c2df8f812f234285a0b2fece81698b4331df9de06ce7ac3c741a830e938a80fd392be6803347ec78f4"} 22:17:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:17:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:17:38 INFO - Contents: 22:17:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb85323257e472721157e8510a422982494f6fe8d3abc588910b91d17bf3a103d3d92939f3c9de304b09103ca45bacbecf52ea5b7ba7c1451913195eff3931cc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/523f9c41a0c55e28dd867d51e1ae92dafff4beca955ca85a8e9adc398b747739fcb2ae4a602217567521f5e14da686943892714ca85f70872c95f1eb88b340c5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23e8ca28f5b42629a1f9d43d95b671aff9da3daf4608fc913ed54544629287d102a7a8afc45232e0de21c6d55fa8262ac412ec73c3ddb357b43c3adfb9070287", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/987a09598e618e68b589b7eb6503a6d6da89a40aa45cf1c2df8f812f234285a0b2fece81698b4331df9de06ce7ac3c741a830e938a80fd392be6803347ec78f4"} 22:17:38 INFO - Running post-run listener: copy_logs_to_upload_dir 22:17:38 INFO - Copying logs to upload dir... 22:17:38 INFO - mkdir: /builds/slave/test/build/upload/logs 22:17:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.207034 ========= master_lag: 2.62 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-16 22:17:41.565741) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-16 22:17:41.569760) ========= 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.xySACekJoL/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.HvF7QRkVjn/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb85323257e472721157e8510a422982494f6fe8d3abc588910b91d17bf3a103d3d92939f3c9de304b09103ca45bacbecf52ea5b7ba7c1451913195eff3931cc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/523f9c41a0c55e28dd867d51e1ae92dafff4beca955ca85a8e9adc398b747739fcb2ae4a602217567521f5e14da686943892714ca85f70872c95f1eb88b340c5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23e8ca28f5b42629a1f9d43d95b671aff9da3daf4608fc913ed54544629287d102a7a8afc45232e0de21c6d55fa8262ac412ec73c3ddb357b43c3adfb9070287", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/987a09598e618e68b589b7eb6503a6d6da89a40aa45cf1c2df8f812f234285a0b2fece81698b4331df9de06ce7ac3c741a830e938a80fd392be6803347ec78f4"} build_url:https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011142 build_url: 'https://queue.taskcluster.net/v1/task/eb9fjHuVSX6xwAuAWqhATw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb85323257e472721157e8510a422982494f6fe8d3abc588910b91d17bf3a103d3d92939f3c9de304b09103ca45bacbecf52ea5b7ba7c1451913195eff3931cc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/523f9c41a0c55e28dd867d51e1ae92dafff4beca955ca85a8e9adc398b747739fcb2ae4a602217567521f5e14da686943892714ca85f70872c95f1eb88b340c5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23e8ca28f5b42629a1f9d43d95b671aff9da3daf4608fc913ed54544629287d102a7a8afc45232e0de21c6d55fa8262ac412ec73c3ddb357b43c3adfb9070287", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/987a09598e618e68b589b7eb6503a6d6da89a40aa45cf1c2df8f812f234285a0b2fece81698b4331df9de06ce7ac3c741a830e938a80fd392be6803347ec78f4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-16 22:17:41.609442) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 22:17:41.609797) ========= 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.xySACekJoL/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.HvF7QRkVjn/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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.005398 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 22:17:41.658088) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-16 22:17:41.658443) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-16 22:17:41.658785) ========= ========= Total master_lag: 2.99 =========