builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0052 starttime: 1491081143.06 results: success (0) buildid: 20170401133222 builduid: f50bd53f5ef242ebb040c44641e061e2 revision: 260ad3e3f8970a637c8acef0b83fde9da19d1044 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-01 14:12:23.063403) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-01 14:12:23.063873) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-01 14:12:23.064147) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-01 14:12:23.089076) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 14:12:23.089452) ========= 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.TiYGyviFA5/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.n0W05udSwo/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-01 14:12:23-- 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% 9.68M=0.001s 2017-04-01 14:12:23 (9.68 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118271 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 14:12:23.231180) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 14:12:23.231514) ========= 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.TiYGyviFA5/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.n0W05udSwo/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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.020713 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 14:12:23.280175) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-01 14:12:23.280533) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 260ad3e3f8970a637c8acef0b83fde9da19d1044 --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 260ad3e3f8970a637c8acef0b83fde9da19d1044 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TiYGyviFA5/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.n0W05udSwo/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-01 14:12:23,327 truncating revision to first 12 chars 2017-04-01 14:12:23,327 Setting DEBUG logging. 2017-04-01 14:12:23,327 attempt 1/10 2017-04-01 14:12:23,327 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/260ad3e3f897?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-01 14:12:24,233 unpacking tar archive at: mozilla-aurora-260ad3e3f897/testing/mozharness/ program finished with exit code 0 elapsedTime=1.162548 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-01 14:12:24.467467) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-01 14:12:24.467822) ========= script_repo_revision: 260ad3e3f8970a637c8acef0b83fde9da19d1044 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-01 14:12:24.468236) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-01 14:12:24.468523) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-01 14:12:24.489167) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-01 14:12:24.489580) ========= /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.TiYGyviFA5/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.n0W05udSwo/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 14:12:24 INFO - MultiFileLogger online at 20170401 14:12:24 in /builds/slave/test 14:12:24 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 14:12:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:12:24 INFO - {'append_to_log': False, 14:12:24 INFO - 'base_work_dir': '/builds/slave/test', 14:12:24 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:12:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:12:24 INFO - 'branch': 'Mozilla-Aurora', 14:12:24 INFO - 'buildbot_json_path': 'buildprops.json', 14:12:24 INFO - 'config_files': ('talos/mac_config.py',), 14:12:24 INFO - 'default_actions': ('clobber', 14:12:24 INFO - 'read-buildbot-config', 14:12:24 INFO - 'download-and-extract', 14:12:24 INFO - 'populate-webroot', 14:12:24 INFO - 'create-virtualenv', 14:12:24 INFO - 'install', 14:12:24 INFO - 'run-tests'), 14:12:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:12:24 INFO - 'download_minidump_stackwalk': True, 14:12:24 INFO - 'download_symbols': 'ondemand', 14:12:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:12:24 INFO - 'tooltool.py': '/tools/tooltool.py', 14:12:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:12:24 INFO - '/tools/misc-python/virtualenv.py')}, 14:12:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:12:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:12:24 INFO - 'gecko_profile': False, 14:12:24 INFO - 'gecko_profile_interval': 0, 14:12:24 INFO - 'installer_path': 'installer.exe', 14:12:24 INFO - 'log_level': 'info', 14:12:24 INFO - 'log_name': 'talos', 14:12:24 INFO - 'log_to_console': True, 14:12:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:12:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:12:24 INFO - 'opt_config_files': (), 14:12:24 INFO - 'pip_index': False, 14:12:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:12:24 INFO - 'cmd': ('bash', 14:12:24 INFO - '-c', 14:12:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:12:24 INFO - 'enabled': True, 14:12:24 INFO - 'halt_on_failure': False, 14:12:24 INFO - 'name': 'check_screen_resolution'},), 14:12:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:12:24 INFO - 'cmd': ('bash', 14:12:24 INFO - '-c', 14:12:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:12:24 INFO - 'enabled': True, 14:12:24 INFO - 'halt_on_failure': False, 14:12:24 INFO - 'name': 'check_screen_resolution'},), 14:12:24 INFO - 'run_cmd_checks_enabled': True, 14:12:24 INFO - 'suite': 'dromaeojs-e10s', 14:12:24 INFO - 'system_bits': '32', 14:12:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:12:24 INFO - 'title': 't-yosemite-r7-0052', 14:12:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:12:24 INFO - 'use_talos_json': True, 14:12:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:12:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:12:24 INFO - 'work_dir': 'build'} 14:12:24 INFO - [mozharness: 2017-04-01 21:12:24.651983Z] Running clobber step. 14:12:24 INFO - Running pre-action listener: _resource_record_pre_action 14:12:24 INFO - Running main action method: clobber 14:12:24 INFO - rmtree: /builds/slave/test/build 14:12:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:12:26 INFO - Running post-action listener: _resource_record_post_action 14:12:26 INFO - [mozharness: 2017-04-01 21:12:26.495988Z] Finished clobber step (success) 14:12:26 INFO - [mozharness: 2017-04-01 21:12:26.496129Z] Running read-buildbot-config step. 14:12:26 INFO - Running pre-action listener: _resource_record_pre_action 14:12:26 INFO - Running main action method: read_buildbot_config 14:12:26 INFO - Using buildbot properties: 14:12:26 INFO - { 14:12:26 INFO - "project": "", 14:12:26 INFO - "product": "firefox", 14:12:26 INFO - "script_repo_revision": "production", 14:12:26 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 14:12:26 INFO - "repository": "", 14:12:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 14:12:26 INFO - "buildid": "20170401133222", 14:12:26 INFO - "pgo_build": "False", 14:12:26 INFO - "basedir": "/builds/slave/test", 14:12:26 INFO - "buildnumber": 15, 14:12:26 INFO - "slavename": "t-yosemite-r7-0052", 14:12:26 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 14:12:26 INFO - "platform": "yosemite_r7", 14:12:26 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:12:26 INFO - "branch": "mozilla-aurora", 14:12:26 INFO - "repo_path": "releases/mozilla-aurora", 14:12:26 INFO - "revision": "260ad3e3f8970a637c8acef0b83fde9da19d1044", 14:12:26 INFO - "stage_platform": "macosx64", 14:12:26 INFO - "builduid": "f50bd53f5ef242ebb040c44641e061e2", 14:12:26 INFO - "slavebuilddir": "test" 14:12:26 INFO - } 14:12:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 14:12:26 INFO - Running post-action listener: _resource_record_post_action 14:12:26 INFO - [mozharness: 2017-04-01 21:12:26.500219Z] Finished read-buildbot-config step (success) 14:12:26 INFO - [mozharness: 2017-04-01 21:12:26.500385Z] Running download-and-extract step. 14:12:26 INFO - Running pre-action listener: _resource_record_pre_action 14:12:26 INFO - Running main action method: download_and_extract 14:12:26 INFO - mkdir: /builds/slave/test/build/tests 14:12:26 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')]} 14:12:26 INFO - trying https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 14:12:26 INFO - Downloading https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/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 14:12:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/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 14:12:27 INFO - Downloaded 1199 bytes. 14:12:27 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 14:12:27 INFO - Using the following test package requirements: 14:12:27 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 14:12:27 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:12:27 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 14:12:27 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:12:27 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 14:12:27 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:12:27 INFO - u'jsshell-mac.zip'], 14:12:27 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:12:27 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 14:12:27 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 14:12:27 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:12:27 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 14:12:27 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:12:27 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 14:12:27 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:12:27 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 14:12:27 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:12:27 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 14:12:27 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:12:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 14:12:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:12:27 INFO - Fetch https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 14:12:29 INFO - Expected file size: 51792713 14:12:29 INFO - Obtained file size: 51792713 14:12:44 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 14:12:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 14:12:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:12:44 INFO - Fetch https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 14:12:46 INFO - Expected file size: 51792713 14:12:46 INFO - Obtained file size: 51792713 14:13:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 14:13:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:13:01 INFO - Fetch https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 14:13:03 INFO - Expected file size: 14404438 14:13:03 INFO - Obtained file size: 14404438 14:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:03 INFO - trying https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 14:13:03 INFO - Downloading https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 14:13:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/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 14:13:05 INFO - Downloaded 74684131 bytes. 14:13:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 14:13:05 INFO - mkdir: /builds/slave/test/properties 14:13:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:13:05 INFO - Writing to file /builds/slave/test/properties/build_url 14:13:05 INFO - Contents: 14:13:05 INFO - build_url:https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 14:13:06 INFO - Running post-action listener: _resource_record_post_action 14:13:06 INFO - Running post-action listener: set_extra_try_arguments 14:13:06 INFO - [mozharness: 2017-04-01 21:13:06.265881Z] Finished download-and-extract step (success) 14:13:06 INFO - [mozharness: 2017-04-01 21:13:06.265997Z] Running populate-webroot step. 14:13:06 INFO - Running pre-action listener: _resource_record_pre_action 14:13:06 INFO - Running main action method: populate_webroot 14:13:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:13:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:13:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:13:06 INFO - '--tppagecycles', 14:13:06 INFO - '7'], 14:13:06 INFO - 'tests': ['tsvgm']}}, 14:13:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:13:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:13:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:13:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:13:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:13:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:13:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:13:06 INFO - 'talos_options': ['--disable-e10s'], 14:13:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:13:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:13:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:13:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:13:06 INFO - 'talos_options': ['--disable-e10s'], 14:13:06 INFO - 'tests': ['damp', 'tps']}, 14:13:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:13:06 INFO - 'tests': ['damp', 'tps']}, 14:13:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:13:06 INFO - 'tests': ['dromaeo_dom']}, 14:13:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:13:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:13:06 INFO - 'tests': ['basic_compositor_video']}, 14:13:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:13:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:13:06 INFO - 'tests': ['a11yr', 14:13:06 INFO - 'ts_paint', 14:13:06 INFO - 'tpaint', 14:13:06 INFO - 'sessionrestore', 14:13:06 INFO - 'sessionrestore_no_auto_restore', 14:13:06 INFO - 'tabpaint']}, 14:13:06 INFO - 'other-e10s': {'tests': ['a11yr', 14:13:06 INFO - 'ts_paint', 14:13:06 INFO - 'tpaint', 14:13:06 INFO - 'sessionrestore', 14:13:06 INFO - 'sessionrestore_no_auto_restore', 14:13:06 INFO - 'tabpaint']}, 14:13:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:13:06 INFO - 'ts_paint', 14:13:06 INFO - 'tpaint', 14:13:06 INFO - 'sessionrestore', 14:13:06 INFO - 'sessionrestore_no_auto_restore', 14:13:06 INFO - 'tabpaint']}, 14:13:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:13:06 INFO - 'ts_paint', 14:13:06 INFO - 'tpaint', 14:13:06 INFO - 'sessionrestore', 14:13:06 INFO - 'sessionrestore_no_auto_restore', 14:13:06 INFO - 'tabpaint']}, 14:13:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:13:06 INFO - 'tests': ['a11yr', 14:13:06 INFO - 'ts_paint', 14:13:06 INFO - 'tpaint', 14:13:06 INFO - 'sessionrestore', 14:13:06 INFO - 'sessionrestore_no_auto_restore', 14:13:06 INFO - 'tabpaint']}, 14:13:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:13:06 INFO - 'tests': ['a11yr', 14:13:06 INFO - 'ts_paint', 14:13:06 INFO - 'tpaint', 14:13:06 INFO - 'sessionrestore', 14:13:06 INFO - 'sessionrestore_no_auto_restore', 14:13:06 INFO - 'tabpaint']}, 14:13:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:13:06 INFO - 'tests': ['tsvgx', 14:13:06 INFO - 'tsvgr_opacity', 14:13:06 INFO - 'tart', 14:13:06 INFO - 'tscrollx', 14:13:06 INFO - 'cart', 14:13:06 INFO - 'tsvg_static']}, 14:13:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:13:06 INFO - 'tsvgr_opacity', 14:13:06 INFO - 'tart', 14:13:06 INFO - 'tscrollx', 14:13:06 INFO - 'cart', 14:13:06 INFO - 'tsvg_static']}, 14:13:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:13:06 INFO - 'talos_options': ['--disable-e10s'], 14:13:06 INFO - 'tests': ['tp5o']}, 14:13:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:13:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:13:06 INFO - 'talos_options': ['--disable-e10s', 14:13:06 INFO - '--xperf_path', 14:13:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:13:06 INFO - 'tests': ['tp5n']}, 14:13:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:13:06 INFO - 'talos_options': ['--xperf_path', 14:13:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:13:06 INFO - 'tests': ['tp5n']}}, 14:13:06 INFO - 'talos.zip': {'path': '', 14:13:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:13:06 INFO - Running post-action listener: _resource_record_post_action 14:13:06 INFO - [mozharness: 2017-04-01 21:13:06.284262Z] Finished populate-webroot step (success) 14:13:06 INFO - [mozharness: 2017-04-01 21:13:06.284359Z] Running create-virtualenv step. 14:13:06 INFO - Running pre-action listener: _resource_record_pre_action 14:13:06 INFO - Running main action method: create_virtualenv 14:13:06 INFO - Creating virtualenv /builds/slave/test/build/venv 14:13:06 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:13:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:13:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:13:06 INFO - Using real prefix '/tools/python27' 14:13:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:13:07 INFO - Installing distribute.............................................................................................................................................................................................done. 14:13:09 INFO - Installing pip.................done. 14:13:09 INFO - Return code: 0 14:13:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:13:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:13:10 INFO - Reading from file tmpfile_stdout 14:13:10 INFO - Output received: 14:13:10 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:13:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4cbda5e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b809d30>, '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 0x10b730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7febe8>, '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.TiYGyviFA5/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.n0W05udSwo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 14:13:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:13:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TiYGyviFA5/Render', 14:13:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:10 INFO - 'HOME': '/Users/cltbld', 14:13:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:10 INFO - 'LOGNAME': 'cltbld', 14:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:10 INFO - 'MOZ_NO_REMOTE': '1', 14:13:10 INFO - 'NO_EM_RESTART': '1', 14:13:10 INFO - 'PAGER': '/bin/cat', 14:13:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:10 INFO - 'PWD': '/builds/slave/test', 14:13:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:10 INFO - 'SHELL': '/bin/bash', 14:13:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n0W05udSwo/Listeners', 14:13:10 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 14:13:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:10 INFO - 'USER': 'cltbld', 14:13:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:10 INFO - 'XPC_FLAGS': '0x0', 14:13:10 INFO - 'XPC_SERVICE_NAME': '0', 14:13:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:13:10 INFO - Cleaning up... 14:13:10 INFO - Return code: 0 14:13:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4cbda5e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b809d30>, '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 0x10b730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7febe8>, '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.TiYGyviFA5/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.n0W05udSwo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 14:13:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:13:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TiYGyviFA5/Render', 14:13:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:10 INFO - 'HOME': '/Users/cltbld', 14:13:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:10 INFO - 'LOGNAME': 'cltbld', 14:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:10 INFO - 'MOZ_NO_REMOTE': '1', 14:13:10 INFO - 'NO_EM_RESTART': '1', 14:13:10 INFO - 'PAGER': '/bin/cat', 14:13:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:10 INFO - 'PWD': '/builds/slave/test', 14:13:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:10 INFO - 'SHELL': '/bin/bash', 14:13:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n0W05udSwo/Listeners', 14:13:10 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 14:13:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:10 INFO - 'USER': 'cltbld', 14:13:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:10 INFO - 'XPC_FLAGS': '0x0', 14:13:10 INFO - 'XPC_SERVICE_NAME': '0', 14:13:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:10 INFO - Downloading/unpacking psutil>=3.1.1 14:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:13:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:13:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:13:14 INFO - Installing collected packages: psutil 14:13:14 INFO - Running setup.py install for psutil 14:13:14 INFO - building 'psutil._psutil_osx' extension 14:13:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:13:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:13:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:13:14 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 14:13:14 INFO - building 'psutil._psutil_posix' extension 14:13:14 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 14:13:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:13:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:13:14 INFO - ^ 14:13:14 INFO - 1 warning generated. 14:13:14 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 14:13:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:13:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:13:15 INFO - Successfully installed psutil 14:13:15 INFO - Cleaning up... 14:13:15 INFO - Return code: 0 14:13:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:13:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4cbda5e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b809d30>, '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 0x10b730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7febe8>, '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.TiYGyviFA5/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.n0W05udSwo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 14:13:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:13:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:13:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TiYGyviFA5/Render', 14:13:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:15 INFO - 'HOME': '/Users/cltbld', 14:13:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:15 INFO - 'LOGNAME': 'cltbld', 14:13:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:15 INFO - 'MOZ_NO_REMOTE': '1', 14:13:15 INFO - 'NO_EM_RESTART': '1', 14:13:15 INFO - 'PAGER': '/bin/cat', 14:13:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:15 INFO - 'PWD': '/builds/slave/test', 14:13:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:15 INFO - 'SHELL': '/bin/bash', 14:13:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n0W05udSwo/Listeners', 14:13:15 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 14:13:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:15 INFO - 'USER': 'cltbld', 14:13:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:15 INFO - 'XPC_FLAGS': '0x0', 14:13:15 INFO - 'XPC_SERVICE_NAME': '0', 14:13:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:13:15 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 14:13:15 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 14:13:15 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 14:13:15 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 14:13:15 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 14:13:15 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 14:13:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:13:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:13:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:13:18 INFO - Installing collected packages: mozsystemmonitor 14:13:18 INFO - Running setup.py install for mozsystemmonitor 14:13:18 INFO - Successfully installed mozsystemmonitor 14:13:18 INFO - Cleaning up... 14:13:18 INFO - Return code: 0 14:13:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:13:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:18 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 0x10ba2bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4cbda5e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b809d30>, '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 0x10b730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7febe8>, '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.TiYGyviFA5/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.n0W05udSwo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 14:13:18 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 14:13:18 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 14:13:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TiYGyviFA5/Render', 14:13:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:18 INFO - 'HOME': '/Users/cltbld', 14:13:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:18 INFO - 'LOGNAME': 'cltbld', 14:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:18 INFO - 'MOZ_NO_REMOTE': '1', 14:13:18 INFO - 'NO_EM_RESTART': '1', 14:13:18 INFO - 'PAGER': '/bin/cat', 14:13:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:18 INFO - 'PWD': '/builds/slave/test', 14:13:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:18 INFO - 'SHELL': '/bin/bash', 14:13:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n0W05udSwo/Listeners', 14:13:18 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 14:13:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:18 INFO - 'USER': 'cltbld', 14:13:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:18 INFO - 'XPC_FLAGS': '0x0', 14:13:18 INFO - 'XPC_SERVICE_NAME': '0', 14:13:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:18 INFO - Downloading/unpacking jsonschema==2.5.1 14:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:13:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:13: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 14:13: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 14:13: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 14:13: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 14:13: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 14:13: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 14:13:21 INFO - Downloading functools32-3.2.3-2.tar.gz 14:13:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:13:21 INFO - warning: no files found matching '*.txt' 14:13:21 INFO - no previously-included directories found matching 'build' 14:13:21 INFO - no previously-included directories found matching 'dist' 14:13:21 INFO - no previously-included directories found matching '.git*' 14:13:21 INFO - Installing collected packages: jsonschema, functools32 14:13:21 INFO - Running setup.py install for functools32 14:13:22 INFO - warning: no files found matching '*.txt' 14:13:22 INFO - no previously-included directories found matching 'build' 14:13:22 INFO - no previously-included directories found matching 'dist' 14:13:22 INFO - no previously-included directories found matching '.git*' 14:13:22 INFO - Successfully installed jsonschema functools32 14:13:22 INFO - Cleaning up... 14:13:22 INFO - Return code: 0 14:13:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:13: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')]} 14:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13: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')]} 14:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4cbda5e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b809d30>, '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 0x10b730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7febe8>, '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.TiYGyviFA5/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.n0W05udSwo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 14:13:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:13:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TiYGyviFA5/Render', 14:13:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:23 INFO - 'HOME': '/Users/cltbld', 14:13:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:23 INFO - 'LOGNAME': 'cltbld', 14:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:23 INFO - 'MOZ_NO_REMOTE': '1', 14:13:23 INFO - 'NO_EM_RESTART': '1', 14:13:23 INFO - 'PAGER': '/bin/cat', 14:13:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:23 INFO - 'PWD': '/builds/slave/test', 14:13:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:23 INFO - 'SHELL': '/bin/bash', 14:13:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n0W05udSwo/Listeners', 14:13:23 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 14:13:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:23 INFO - 'USER': 'cltbld', 14:13:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:23 INFO - 'XPC_FLAGS': '0x0', 14:13:23 INFO - 'XPC_SERVICE_NAME': '0', 14:13:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:13:23 INFO - Cleaning up... 14:13:23 INFO - Return code: 0 14:13:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4cbda5e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b809d30>, '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 0x10b730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7febe8>, '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.TiYGyviFA5/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.n0W05udSwo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 14:13:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:13:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TiYGyviFA5/Render', 14:13:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:23 INFO - 'HOME': '/Users/cltbld', 14:13:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:23 INFO - 'LOGNAME': 'cltbld', 14:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:23 INFO - 'MOZ_NO_REMOTE': '1', 14:13:23 INFO - 'NO_EM_RESTART': '1', 14:13:23 INFO - 'PAGER': '/bin/cat', 14:13:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:23 INFO - 'PWD': '/builds/slave/test', 14:13:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:23 INFO - 'SHELL': '/bin/bash', 14:13:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n0W05udSwo/Listeners', 14:13:23 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 14:13:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:23 INFO - 'USER': 'cltbld', 14:13:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:23 INFO - 'XPC_FLAGS': '0x0', 14:13:23 INFO - 'XPC_SERVICE_NAME': '0', 14:13:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:24 INFO - Downloading/unpacking blobuploader==1.2.4 14:13:24 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 14:13:24 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 14:13:24 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 14:13:24 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 14:13:24 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 14:13:24 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 14:13:27 INFO - Downloading blobuploader-1.2.4.tar.gz 14:13:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:13:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:13:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:28 INFO - Downloading docopt-0.6.1.tar.gz 14:13:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:13:28 INFO - Installing collected packages: blobuploader, requests, docopt 14:13:28 INFO - Running setup.py install for blobuploader 14:13:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:13:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:13:28 INFO - Running setup.py install for requests 14:13:29 INFO - Running setup.py install for docopt 14:13:29 INFO - Successfully installed blobuploader requests docopt 14:13:29 INFO - Cleaning up... 14:13:29 INFO - Return code: 0 14:13:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:13:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:29 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 0x10ba2bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4cbda5e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b809d30>, '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 0x10b730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7febe8>, '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.TiYGyviFA5/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.n0W05udSwo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 14:13:29 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 14:13:29 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 14:13:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TiYGyviFA5/Render', 14:13:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:29 INFO - 'HOME': '/Users/cltbld', 14:13:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:29 INFO - 'LOGNAME': 'cltbld', 14:13:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:29 INFO - 'MOZ_NO_REMOTE': '1', 14:13:29 INFO - 'NO_EM_RESTART': '1', 14:13:29 INFO - 'PAGER': '/bin/cat', 14:13:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:29 INFO - 'PWD': '/builds/slave/test', 14:13:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:29 INFO - 'SHELL': '/bin/bash', 14:13:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n0W05udSwo/Listeners', 14:13:29 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 14:13:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:29 INFO - 'USER': 'cltbld', 14:13:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:29 INFO - 'XPC_FLAGS': '0x0', 14:13:29 INFO - 'XPC_SERVICE_NAME': '0', 14:13:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:13:29 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-xrUDLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:13:29 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-jux9uv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:13:29 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-SZ99kw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:13:29 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-4qoDod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:13:29 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-Y5RWGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:13:30 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-L1U_VV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:13:30 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-sYmhYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:13:30 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-5ZjPzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:13:30 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-XEMDIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:13:30 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-GPXxMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:13:30 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-4mRcIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:13:30 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-ZT4Cnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:13:30 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-yy707N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:13:30 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-7HOkXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:31 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-xx9Wfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:13:31 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-iC76Ts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:13:31 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-eN53KV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:13:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:13:31 INFO - Running setup.py install for manifestparser 14:13:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:13:31 INFO - Running setup.py install for mozcrash 14:13:31 INFO - Running setup.py install for mozdebug 14:13:31 INFO - Running setup.py install for mozdevice 14:13:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:13:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:13:31 INFO - Running setup.py install for mozfile 14:13:32 INFO - Running setup.py install for mozhttpd 14:13:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:13:32 INFO - Running setup.py install for mozinfo 14:13:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:13:32 INFO - Running setup.py install for mozInstall 14:13:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:13:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:13:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:13:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:13:32 INFO - Running setup.py install for mozleak 14:13:32 INFO - Running setup.py install for mozlog 14:13:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:13:32 INFO - Running setup.py install for moznetwork 14:13:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:13:32 INFO - Running setup.py install for mozprocess 14:13:33 INFO - Running setup.py install for mozprofile 14:13:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:13:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:13:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:13:33 INFO - Running setup.py install for mozrunner 14:13:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:13:33 INFO - Running setup.py install for mozscreenshot 14:13:33 INFO - Running setup.py install for moztest 14:13:33 INFO - Running setup.py install for mozversion 14:13:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:13:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:13:33 INFO - Cleaning up... 14:13:33 INFO - Return code: 0 14:13:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:13:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4cbda5e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b809d30>, '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 0x10b730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7febe8>, '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.TiYGyviFA5/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.n0W05udSwo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 14:13:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:13:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:13:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TiYGyviFA5/Render', 14:13:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:33 INFO - 'HOME': '/Users/cltbld', 14:13:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:33 INFO - 'LOGNAME': 'cltbld', 14:13:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:33 INFO - 'MOZ_NO_REMOTE': '1', 14:13:33 INFO - 'NO_EM_RESTART': '1', 14:13:33 INFO - 'PAGER': '/bin/cat', 14:13:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:33 INFO - 'PWD': '/builds/slave/test', 14:13:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:33 INFO - 'SHELL': '/bin/bash', 14:13:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n0W05udSwo/Listeners', 14:13:33 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 14:13:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:33 INFO - 'USER': 'cltbld', 14:13:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:33 INFO - 'XPC_FLAGS': '0x0', 14:13:33 INFO - 'XPC_SERVICE_NAME': '0', 14:13:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:13:34 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-33X3xF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:13:34 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)) 14:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:13:34 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-czs687-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:13:34 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)) 14:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:13:34 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-lorOnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:13:34 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)) 14:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:13:34 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-ch8LHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:13:34 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)) 14:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:13:34 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-9oesMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:13:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:13:34 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-M5MBkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:13:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:13:34 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-jDQYpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:13:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:13:34 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-4AgIbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:13:35 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)) 14:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:13:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-Y9I7Kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:13:35 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)) 14:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:13:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-l7IyAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:13:35 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)) 14:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:13:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-n0aHXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:13:35 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)) 14:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:13:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-tHKKxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:13:35 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)) 14:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:13:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-nCwtOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:13:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-cl06Rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-Vl46Vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:13:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-j_o2uV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:13:35 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-ZJFB_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:13:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:38 INFO - Downloading blessings-1.6.tar.gz 14:13:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:13:38 INFO - Installing collected packages: blessings 14:13:38 INFO - Running setup.py install for blessings 14:13:38 INFO - Successfully installed blessings 14:13:38 INFO - Cleaning up... 14:13:38 INFO - Return code: 0 14:13:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:13:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:13:39 INFO - Reading from file tmpfile_stdout 14:13:39 INFO - Current package versions: 14:13:39 INFO - blessings == 1.6 14:13:39 INFO - blobuploader == 1.2.4 14:13:39 INFO - docopt == 0.6.1 14:13:39 INFO - functools32 == 3.2.3-2 14:13:39 INFO - jsonschema == 2.5.1 14:13:39 INFO - manifestparser == 1.1 14:13:39 INFO - mozInstall == 1.12 14:13:39 INFO - mozcrash == 1.0 14:13:39 INFO - mozdebug == 0.1 14:13:39 INFO - mozdevice == 0.49 14:13:39 INFO - mozfile == 1.2 14:13:39 INFO - mozhttpd == 0.7 14:13:39 INFO - mozinfo == 0.9 14:13:39 INFO - mozleak == 0.1 14:13:39 INFO - mozlog == 3.4 14:13:39 INFO - moznetwork == 0.27 14:13:39 INFO - mozprocess == 0.25 14:13:39 INFO - mozprofile == 0.28 14:13:39 INFO - mozrunner == 6.13 14:13:39 INFO - mozscreenshot == 0.1 14:13:39 INFO - mozsystemmonitor == 0.3 14:13:39 INFO - moztest == 0.8 14:13:39 INFO - mozversion == 1.4 14:13:39 INFO - psutil == 3.1.1 14:13:39 INFO - requests == 1.2.3 14:13:39 INFO - wsgiref == 0.1.2 14:13:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:13:39 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')]} 14:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:39 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')]} 14:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:39 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 0x10ba2bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4cbda5e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b809d30>, '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 0x10b730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7febe8>, '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.TiYGyviFA5/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.n0W05udSwo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 14:13:39 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 14:13:39 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 14:13:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TiYGyviFA5/Render', 14:13:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:39 INFO - 'HOME': '/Users/cltbld', 14:13:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:39 INFO - 'LOGNAME': 'cltbld', 14:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:39 INFO - 'MOZ_NO_REMOTE': '1', 14:13:39 INFO - 'NO_EM_RESTART': '1', 14:13:39 INFO - 'PAGER': '/bin/cat', 14:13:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:39 INFO - 'PWD': '/builds/slave/test', 14:13:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:39 INFO - 'SHELL': '/bin/bash', 14:13:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n0W05udSwo/Listeners', 14:13:39 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 14:13:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:39 INFO - 'USER': 'cltbld', 14:13:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:39 INFO - 'XPC_FLAGS': '0x0', 14:13:39 INFO - 'XPC_SERVICE_NAME': '0', 14:13:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:39 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)) 14:13:39 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)) 14:13:39 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)) 14:13:39 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)) 14:13:39 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)) 14:13:39 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)) 14:13:39 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)) 14:13:39 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)) 14:13:39 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)) 14:13:39 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)) 14:13:39 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)) 14:13:39 INFO - Cleaning up... 14:13:39 INFO - Return code: 0 14:13:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:13:39 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')]} 14:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:39 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')]} 14:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:39 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 0x10ba2bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba2e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4cbda5e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b809d30>, '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 0x10b730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7febe8>, '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.TiYGyviFA5/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.n0W05udSwo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 14:13:39 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 14:13:39 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 14:13:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TiYGyviFA5/Render', 14:13:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:13:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:13:39 INFO - 'HOME': '/Users/cltbld', 14:13:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:13:39 INFO - 'LOGNAME': 'cltbld', 14:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:39 INFO - 'MOZ_NO_REMOTE': '1', 14:13:39 INFO - 'NO_EM_RESTART': '1', 14:13:39 INFO - 'PAGER': '/bin/cat', 14:13:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:39 INFO - 'PWD': '/builds/slave/test', 14:13:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:13:39 INFO - 'SHELL': '/bin/bash', 14:13:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n0W05udSwo/Listeners', 14:13:39 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 14:13:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:13:39 INFO - 'USER': 'cltbld', 14:13:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:13:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:39 INFO - 'XPC_FLAGS': '0x0', 14:13:39 INFO - 'XPC_SERVICE_NAME': '0', 14:13:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:13:39 INFO - Cleaning up... 14:13:39 INFO - Return code: 0 14:13:39 INFO - Running post-action listener: _resource_record_post_action 14:13:39 INFO - Running post-action listener: _start_resource_monitoring 14:13:39 INFO - Starting resource monitoring. 14:13:39 INFO - [mozharness: 2017-04-01 21:13:39.739204Z] Finished create-virtualenv step (success) 14:13:39 INFO - [mozharness: 2017-04-01 21:13:39.740206Z] Running install step. 14:13:39 INFO - Running pre-action listener: _resource_record_pre_action 14:13:39 INFO - Running main action method: install 14:13:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:13:39 INFO - Reading from file tmpfile_stdout 14:13:39 INFO - Detecting whether we're running mozinstall >=1.0... 14:13:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:13:40 INFO - Reading from file tmpfile_stdout 14:13:40 INFO - Output received: 14:13:40 INFO - Usage: mozinstall [options] installer 14:13:40 INFO - Options: 14:13:40 INFO - -h, --help show this help message and exit 14:13:40 INFO - -d DEST, --destination=DEST 14:13:40 INFO - Directory to install application into. [default: 14:13:40 INFO - "/builds/slave/test"] 14:13:40 INFO - --app=APP Application being installed. [default: firefox] 14:13:40 INFO - mkdir: /builds/slave/test/build/application 14:13:40 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'] 14:13:40 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 14:14:00 INFO - Reading from file tmpfile_stdout 14:14:00 INFO - Output received: 14:14:00 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:14:00 INFO - Running post-action listener: _resource_record_post_action 14:14:00 INFO - [mozharness: 2017-04-01 21:14:00.143653Z] Finished install step (success) 14:14:00 INFO - [mozharness: 2017-04-01 21:14:00.143792Z] Running run-tests step. 14:14:00 INFO - Running pre-action listener: _resource_record_pre_action 14:14:00 INFO - Running main action method: run_tests 14:14:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:14:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:14:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:14:00 INFO - 2017-04-01 14:14:00.158 screenresolution[1916:17624] starting screenresolution argv=screenresolution get 14:14:00 INFO - 2017-04-01 14:14:00.161 screenresolution[1916:17624] Display 0: 1600x1200x32@60 14:14:00 INFO - 2017-04-01 14:14:00.167 screenresolution[1917:17627] starting screenresolution argv=screenresolution list 14:14:00 INFO - Available Modes on Display 0 14:14:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:14:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:14:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:14:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:14:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:14:00 INFO - Intel Iris: 14:14:00 INFO - Chipset Model: Intel Iris 14:14:00 INFO - Type: GPU 14:14:00 INFO - Bus: Built-In 14:14:00 INFO - VRAM (Dynamic, Max): 1536 MB 14:14:00 INFO - Vendor: Intel (0x8086) 14:14:00 INFO - Device ID: 0x0a2e 14:14:00 INFO - Revision ID: 0x0009 14:14:00 INFO - Displays: 14:14:00 INFO - Display: 14:14:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:14:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:14:00 INFO - Display Serial Number: 1600x1200 60 14:14:00 INFO - Main Display: Yes 14:14:00 INFO - Mirror: Off 14:14:00 INFO - Online: Yes 14:14:00 INFO - Rotation: Supported 14:14:00 INFO - Return code: 0 14:14:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:14:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:14:00 INFO - Python 2.7.3 14:14:00 INFO - Return code: 0 14:14:00 INFO - grabbing minidump binary from tooltool 14:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:14:00 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 0x10b809d30>, '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 0x10b730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7febe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:14:00 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 14:14:00 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 14:14:00 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 14:14:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:14:00 INFO - Return code: 0 14:14:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:14:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:14:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:14:00 INFO - ENV: RUST_BACKTRACE is now 1 14:14:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:14:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:14:00 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/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 14:14:00 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/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0052 --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 14:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TiYGyviFA5/Render', 14:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:14:00 INFO - 'HOME': '/Users/cltbld', 14:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:14:00 INFO - 'LOGNAME': 'cltbld', 14:14:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:14:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:14:00 INFO - 'MOZ_NO_REMOTE': '1', 14:14:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:14:00 INFO - 'NO_EM_RESTART': '1', 14:14:00 INFO - 'PAGER': '/bin/cat', 14:14:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:14:00 INFO - 'PWD': '/builds/slave/test', 14:14:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:14:00 INFO - 'RUST_BACKTRACE': '1', 14:14:00 INFO - 'SHELL': '/bin/bash', 14:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.n0W05udSwo/Listeners', 14:14:00 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 14:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:14:00 INFO - 'USER': 'cltbld', 14:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:14:00 INFO - 'XPC_FLAGS': '0x0', 14:14:00 INFO - 'XPC_SERVICE_NAME': '0', 14:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:14:00 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/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 14:14:00 INFO - mozversion application_buildid: 20170401133222 14:14:00 INFO - mozversion application_changeset: 260ad3e3f8970a637c8acef0b83fde9da19d1044 14:14:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:14:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:14:00 INFO - mozversion application_name: Firefox 14:14:00 INFO - mozversion application_remotingname: firefox-dev 14:14:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:14:00 INFO - mozversion application_vendor: Mozilla 14:14:00 INFO - mozversion application_version: 54.0a2 14:14:00 INFO - mozversion platform_buildid: 20170401133222 14:14:00 INFO - mozversion platform_changeset: 260ad3e3f8970a637c8acef0b83fde9da19d1044 14:14:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:14:00 INFO - mozversion platform_version: 54.0a2 14:14:00 INFO - using testdate: 1491081240 14:14:00 INFO - actual date: 1491081240 14:14:00 INFO - starting webserver on 'localhost:52955' 14:14:00 INFO - SUITE-START | Running 2 tests 14:14:00 INFO - TEST-START | dromaeo_css 14:14:00 INFO - operating with platform_type : mac_ 14:14:00 INFO - Initialising browser for dromaeo_css test... 14:14:00 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpxlIwqS/profile http://localhost:52955/getInfo.html) 14:14:03 INFO - PROCESS | 1927 | 2017-04-01 14:14:03.327 plugin-container[1929:17798] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3b, name = 'com.apple.tsm.portname' 14:14:03 INFO - PROCESS | 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:14:03 INFO - PROCESS | 1927 | 2017-04-01 14:14:03.330 plugin-container[1929:17798] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 14:14:03 INFO - PROCESS | 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:14:03 INFO - PROCESS | 1927 | 2017-04-01 14:14:03.330 plugin-container[1929:17798] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:14:03 INFO - PROCESS | 1927 | __metrics Screen width/height:1600/1200 14:14:03 INFO - PROCESS | 1927 | colorDepth:24 14:14:03 INFO - PROCESS | 1927 | Browser inner width/height: 1024/689 14:14:03 INFO - PROCESS | 1927 | __metrics 14:14:03 ERROR - PROCESS | 1927 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 14:14:04 INFO - TEST-INFO | 1927: exit 0 14:14:04 INFO - Browser initialized. 14:14:04 INFO - Running cycle 1/1 for dromaeo_css test... 14:14:04 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpxlIwqS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:14:06 INFO - PROCESS | 1930 | 2017-04-01 14:14:06.570 plugin-container[1931:17927] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 14:14:06 INFO - PROCESS | 1930 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:14:06 INFO - PROCESS | 1930 | 2017-04-01 14:14:06.571 plugin-container[1931:17927] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 14:14:06 INFO - PROCESS | 1930 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:14:06 INFO - PROCESS | 1930 | 2017-04-01 14:14:06.571 plugin-container[1931:17927] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:14:16 INFO - PROCESS | 1930 | RSS: Main: 220864512 14:14:16 INFO - PROCESS | 1930 | 14:17:19 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52955/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52955/tests/dromaeo/cssquery-ext.html) 14:17:21 INFO - PROCESS | 1930 | RSS: Main: 243552256 14:17:21 INFO - PROCESS | 1930 | 14:19:04 INFO - PROCESS | 1930 | ************************* 14:19:04 INFO - PROCESS | 1930 | A coding exception was thrown and uncaught in a Task. 14:19:04 INFO - PROCESS | 1930 | 14:19:04 ERROR - PROCESS | 1930 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:19:04 INFO - PROCESS | 1930 | Full stack: 14:19:04 INFO - PROCESS | 1930 | ************************* 14:20:23 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52955/tests/dromaeo/cssquery-ext.html (next: http://localhost:52955/tests/dromaeo/cssquery-jquery.html) 14:20:24 INFO - PROCESS | 1930 | RSS: Main: 251392000 14:20:24 INFO - PROCESS | 1930 | 14:23:26 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52955/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52955/tests/dromaeo/cssquery-mootools.html) 14:23:26 INFO - PROCESS | 1930 | RSS: Main: 258174976 14:23:26 INFO - PROCESS | 1930 | 14:26:28 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52955/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52955/tests/dromaeo/cssquery-prototype.html) 14:26:29 INFO - PROCESS | 1930 | RSS: Main: 255356928 14:26:29 INFO - PROCESS | 1930 | 14:29:32 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52955/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52955/tests/dromaeo/cssquery-yui.html) 14:29:33 INFO - PROCESS | 1930 | RSS: Main: 260337664 14:29:33 INFO - PROCESS | 1930 | 14:32:36 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52955/tests/dromaeo/cssquery-yui.html (next: http://localhost:52955/tests/dromaeo/cssquery-dojo.html) 14:32:36 INFO - PROCESS | 1930 | RSS: Main: 256503808 14:32:36 INFO - PROCESS | 1930 | 14:32:36 INFO - PROCESS | 1930 | __start_tp_report 14:32:36 INFO - PROCESS | 1930 | _x_x_mozilla_page_load 14:32:36 INFO - PROCESS | 1930 | _x_x_mozilla_page_load_details 14:32:36 INFO - PROCESS | 1930 | |i|pagename|runs| 14:32:36 INFO - PROCESS | 1930 | |0;dojo.html;3054.5418187271894;3450.4479283314668;3456.170519075423;3476.947845782314;3496.930061398771;4904.828331008411;5895.439933206344;6000.069989151678;6000.3699611540815;6018.488428483573;5987.580869339148;6000.189974353461;6004.399560043993;6028.21633187685;6055;4483.34991426243;5372.382176049756;5415.810446634368;5466.34403871534;5496.587755918322;5495.093309603911;5496.7526461309335;5503.6147469677135;5510.889782204354;5522.447755224465;5279.234510995903;5290.365156181264;5395.541378982791;5501.559875209973;5519.172124181365;5401.70290634015;5407.324084489419;5420.322459692538;5450.754716037788;5482.5613950883835;5335.359756829205;5443.129099344125;5444.292242008553;5482.506574408282;5527.585431092684;6045.818625441244;6118.847028824315;6120.357362476965;6134.447899689049;6158.414950579688;6275.96862015687;6281.403266689657;6295.968520157416;6314.242290925118;6449.06488559161;6440.581362211488;6461.12774775404;6472.482201423874;6476.287608363076;6481.546291759579;6120.755169793248;6146.569740118193;6163.753449862;6179.876402471926;6203.2245969253845;6262.749490020348;6337.651429171347;6356.078368636522;6408.327125651833;6581.177352830896;6285.057241413733;6286.151369565142;6363.936360636334;6379.0112532556595;6400.615963042233;6403.103565500834;6431.067495213168;6440.388163124496;6442.259140198843;6456.063870738717;6218.409251121137;6225.595336303126;6384.936150638434;6409.134766806515;6412.871742565123;6265.937340626627;6304.936950630527;6360.840978975562;6378.840528986812;6405.039244113327;5038.672486288379;5074.061298659686;5127.384713834289;5166.844994650167;5210.817621383309;4601.861944141681;4640.443146822403;4640.628749700016;4652.069586082797;4685.01614660925;5989.580729348903;6055.6063855849225;6075.149479072933;6081.483073938764;6085.908711369333;6279.215098112736;6315.747370105236;6324.841878953063;6330.303666596671;6444.581102228341;6064.120702498113;6073.51411887048;6073.75704971805;6081.969590152109;6100.908486372708;5684.54523638108;5685.118806584986;5692.800751973661;5697;5703.971480142573;6306.148669929501;6333.303336632875;6468;6612.669366531751;6649.168853893263;6368.172137622079;6375.649339286384;6434.935650643433;6442.710078046405;6446;5448.673079615077;6555.115059466911;6573.1783527059115;6594.142761440982;6621.9337806621315;6198.535109866688;6243.843903902439;6375.904361434675;6398.0402939559435;6399.936000639934;6348.492120630431;6375.9362406377195;6404.487640988803;6441.1626488556185;6443.548951573345;6311.368863113652;6344.080108384351;6351.523635727432;6361.109444677842;6379.649119298483;6117.705611546597;6302.621842689453;6352.872942541031;6361.2048493938255;6389.329120442287;4991.575716064175;5002.974985125051;5016.724080175625;5609.49514543693;6135.6932153393045;6285.5600107992;6312.589681670676;6314.968425157845;6324.3991820776955;6329.1139240505445;5866.090755932837;6058.4244496772735;6073.537438594785;6232.844178895564;6237.5633705641985 14:32:36 INFO - PROCESS | 1930 | |1;ext.html;660.6828722213337;808.2321794295419;956.2732323434188;964.5177411294353;979.4025644356941;37750.62249377505;39332.60667393329;39387.80306098473;39430.01424964378;39436.40845387321;38048;38156.236875262475;38156.66451674192;38272.42591361132;38390;7771.494852834548;10493.475326233696;10780;10939.343639381623;10954.959278868495;10679.83980240297;10752.892471075267;10758.731031724192;10793.892061079367;10811.729706757314;10773.192010599198;10788.838167427495;10822.404767737771;10829.783404331909;10839.891601084006;10763.408012559306;10775.407352595605;10790.460476976119;10795.78408431831;10796.51415686296;10544.472776361228;10594.735131621694;10606;10608.840867386996;10617;33352.49971250433;33416.6658333416;33535.83232083848;33702.98891033273;33810;3706.2772759311947;3764.887053388403;3777.508923839883;3830.4637350770636;3833.2141910908476;25185.62221566678;25466;25867.482650347076;25971.350716232057;26224.73775262242;3004.8948286809855;3031.10582378199;3033.9848300758576;3051.450738867003;3052.3895220955897;330647.34676326707;331371;332027;332899.6710032893;332922;45146.32280515795;45218.095638087056;45223;45347.54652453433;45389.09221815545;46583.534164658595;46855.531444685796;46916.765416172704;47133.76433117812;47135;13854.376553055137;13857.584272471842;13983.510577129751;13991.300434978413;14001.29993500321;11886.762264754656;12029.398530073462;12096.27422354644;12104;12111.87888121125;8331.875021874677;11384.089272858317;11925.701857453632;11955.76088478243;12043.81934270969;5710.371859095496;5812.3025236971835;5815.73829177688;5822.592418530747;5826.533877289807;937.2033771294346;1070.8661417322835;1077.7828182874114;1093.8842380771728;1122.4107343644653;19188.80811191898;21095.68356474654;23216;25221;25720;1145.931244125355;1158.8030034894089;1204.2894692131683;1237.2205510528308;1255.4350542256022;10975.341479511255;13418.731625367438;13420.26188559619;13689.726205475834;13736.038477306492;459293;459297;460455;461772;465839;55813;55860.44139558553;56259.43740562624;56266;56546;1396.406527225925;1446.7323545144181;1446.7395868743727;1452.4771082410114;1456.2936975566843;25525.744742552335;25804.870975645;25901.834417451635;26364.077257295903;26472.205833825017;45965.08069838651;46000;46144.769276153405;46456.7677161612;46915;446377;447408;449589.7520512376;449998;455218;30940.22649433782;31478.055658330286;31540.684593153775;31560.36879262448;31663;37329.81335093416;39392;43060.35409468923;43303.35044974265;43376.349354760336;26422.07522736695;26987;27655.308617283925;28680.85276588913;28773.992910248042;23406.64890026685;24981.875815588704;26886.655667216954;27781.861090694416;28750.13749587516;26781;26836;27260.727392725817;27380.58929116025;27465.17604471869;21914.890425547772;27287.044953426535;27358.726412735617;27684.44631107326;28618.284542886595;21310.6148100373;22129.557408852055;22450.5509889798;23307.417314567272;23373.88313058424 14:32:36 INFO - PROCESS | 1930 | |2;jquery.html;391.4070183671737;404.14321638127166;408.77721641705284;409.75414751149293;433.77877282585894;19710.90144549277;20250.89874550629;20619.706631393987;20979.055942482588;21213;21733.782662173373;21746.89126554369;22056.558868822653;22732.204372846954;22912.312630621065;6816.113905192318;7027.191872934627;7038.401735852459;7090.468214883878;7099.964500177518;6939.23668396476;7350.558966462003;7714.537127772352;7879.054513458393;7880.80297992549;6438.645874476902;7290.270972902694;7616.238376162395;7804.102528209243;7866.213378662145;6402.359764023584;7607.733729319502;7625;7625.923740762577;7693.192214817447;6757.662116894185;6994.91578805286;7200.315969982843;7421.480496365257;7467.738629147953;25114.49771004588;25549.616755748677;26395.34011649705;26607.935682572675;26614.467710645873;2625.737426257359;2817.971820281791;2850.2446851584345;2900.941981160386;2924.4443555724342;16329.59176020597;16410.507684769476;16923.392277733594;16981.3207471701;17267.82732172675;2049.067674208232;2082;2088.6866969954476;2100.653392190287;2129.371835308588;130352;158676;172095;173632;175558;14643.140107932506;19751.901240493993;19893.801061989485;21915.890420547796;22406;32769;33152.83965061211;33593.83203083969;33794.32411351759;33882.66117338795;10357.896421035844;10474.895251047392;10721.445390418501;10796.190285728602;10874.782504349869;8928.330375221884;8938.10618938114;8953.686620968365;9084.591193396313;9097.862767154105;9835.852462213072;9850.704478865646;9883.45640989738;10037.548310325907;10050.587858913808;4121.083696081277;4170.541240463546;4173.9582604174175;4198.832046718158;4206.348016057516;989.9406035637885;1000.9949950250348;1001.7044971733357;1005.3415013166388;1043.3844032020993;20828.791712082988;21072.208862246574;21208.575828483652;21283.680744788842;21408;881.4402854187509;981.455292312759;993.6373223773415;1027.6505987964129;1029.8928651699453;10097.899021009696;10897.727556810985;10996.340219586851;11028;11037.944810275898;434320;498922;678953;758371;787944;115528;122710;133756.3312183433;157731;168822;1186.679596508955;1261.9116661833766;1321.6035189443128;1324.218710960538;1335.826342575459;15993.520194394187;19021.144048517574;19628.509287267934;19873.403797886087;20471;49435.76410589764;65307.69384612186;65893;67334.66332668305;68827;90400;90580;93315.13369732704;93683.06316936744;95196.52401737947;9764.511774411108;11689;11743.706407339885;11804.114691398008;11960.641180764243;13147.211167329991;13305.80041299362;13398.39219293652;13500.122492037986;13525.917926565655;9742.269329800152;10996.670099897015;11326.037286830711;11378.924045906311;11485.712857178638;10244.231682623982;10453.059224669816;10560.674543651474;10741.248112632353;10784.730381740519;11317.830232546683;11334.14993875446;11480.827787583026;11581.652550423163;11648.76702465929;11591.942040289745;11643.41782910834;11714.414279286171;11987.940060299643;12018.879811201776;9543.18882894934;11262.774744505228;11267.436628168723;11305.604303849326;11310.773784524099 14:32:36 INFO - PROCESS | 1930 | |3;mootools.html;1311.626186536837;1369.4659082957646;1467.1930438258955;1523.0937592132677;1560.9697599584276;10891.618793342235;11091.5563377465;11435.599754008612;11453.770924581524;11562.59530916418;10945.288556243839;11167.994880460757;11240.325580465178;11249.775004499905;11269;2405.470796424788;2453.092355828337;2454.006127518358;2456.349067497106;2465.1125594785863;2343.4024323797407;2429.574824405731;2440.3533063738123;2443.51129774045;2449.9142530011454;2364.5034542746043;2417.3231495181376;2425.9393515162174;2431.1612493689736;2434.087217293515;2279.7150356205475;2402.6636270922086;2408.397900524869;2419.2379400488967;2434.379233295498;2035.8880261585607;2113.883736394513;2328.161861729776;2427.4052857050137;2438.061346381638;11195.776084478264;11275.097992160607;11283.43582820864;11307.717307067307;11326.32042077478;5875.706214689291;6025.879482410371;6091.390860913917;6091.604045737013;6096.908546371808;3616.0959760059986;3703.407454807218;3763.9059023524633;3784.8864534064023;3830.425436184567;1163.97090072748;1202.326697049655;1224.4857159992825;1248.182440501473;1270.8665590112998;15716;15803.841961580467;15843.207839607974;15989.680206395808;16059.036457812568;4733.431988161374;4802.711837289774;4833.709977401367;4842.443119041285;4861.51384861511;11844.230125041844;12043.939780301218;12053.57812476559;12059.095567832448;12116.81824772629;5809.535237181015;5887;5893.381194974551;5898.528117750569;5924.6149000314845;5062.164742817467;5320.76056577455;5324.1747529133045;5339.119045357473;5364.30264065669;3840.519935008124;3949.822257998397;3984.6812255019527;4045.5752146024834;4049.8987525312104;1065.898739619735;1068.6687126990657;1071.373246650718;1083.8374243863484;1087.9238453308192;910.6266430763355;914.4284821986258;929.3283941066453;934.6495064350869;979.456401697065;6373.585901066637;6383.808485745435;6441.1626488556185;6559.409653131241;6632.336766323426;925.1488630459993;933.4026223217146;944.2068662323682;971.5967873332629;981.4503877828296;3710.276496083238;3930.8820735377985;4078.34746440574;4139.4204811326435;4285.292926667127;13484.460621575223;14026.50907218263;14029.158250505003;14050.297485125702;14084.507042253472;13565.389557469938;13813.295222215582;13859.722805543832;13962.93018534921;13981.300934953211;9894.109530142321;10149.188064954786;10255.179585633281;10260.692179234635;10282.948585257025;4834.6374021947795;6507.772227971998;6570.737170513126;6614.570052946543;6617.2721000688925;12125.636230913087;12279.570215042431;12291.877081229073;12305.56930507428;12316.753664926831;12365.505379784709;12581.370931453574;12639.494420223456;12816.91056260191;12821.23072615646;8500.872486912578;8504.617292221741;8548.401611887108;8611.913880861111;8639.09289524616;10315.27793054509;10357.792844143225;10372.58509659605;10374.377537347784;10442.164626829683;8892.955535222283;8905.975812781613;8981.685641002534;8997.100289970951;9139.086091390807;8533.658653653783;8713.085126061926;8725.083866194213;8793.384463087523;8898.976617689048;7497.425744544397;7759.650815713419;7886.802829929297;7893.842123157619;7897.763067107996;5281.709505977208;5630.268065151504;5838.824835254779;5840.532757379315;5853.180554722428;5070.645054846125;5725.255716756795;5776.797812076557;5980.760769569169;6043.909341359795 14:32:36 INFO - PROCESS | 1930 | |4;prototype.html;328.10918356661665;370.90028066781986;380.4958430080143;385.32375680680417;389.73303287248245;4679.251319788835;4960.20636698128;5237.554807841338;5277.340332458442;5359.758810853511;4569.520200378962;4928.704277743338;5077.238414237866;5160.741962901859;5175.715335656538;4095.2423801596724;4189.560096189902;4281.272183728758;4282.14357128574;4284.785760711968;3498.8425520851624;4084.693647976399;4247.87256382309;4291.613754762071;4316.54676258993;3511.9824400878088;4304.504981927072;4322.243607368714;4323.740575565461;4331;3475.4091804393315;3649.94525082124;4003.6596889264447;4272.273713468718;4295.713778959083;3583.157957879896;3671.1005803578028;3876.127871228979;3912.5891781363116;3931.6658084062888;4067.5729048449784;4270.530241673413;5018.949810501921;5053.570446512051;5058.293812810129;4096.426500289962;4506.211413002711;4556.612687921531;4730.955856246799;4988.800447982077;4816.903661926777;5039.899202015976;5276.076686579833;5515.931525677871;5531.529819965308;4140.399642051916;4439.933400998988;4443.933340999855;4467;4486.8878278042985;4718.433787945434;5004.824831130929;5150.690958542499;5884.7940322088525;5900.645961242296;4313.411952828278;4646.09510100857;4822.924628688753;5029.91856750793;5050.005247927048;5125.256837758504;5152.47055766801;5159.672011552528;5176.689398636094;5193.350831146106;4671.789769460314;4765.571098601143;4798.2802579613335;4977.938567780789;5264.078786212397;5560.443955604488;5567.944320556742;5582.720863956786;5623.971880140573;5652.547796176296;1491.8284397294233;1532.0884073975967;1634.4361195387573;1736.3835838277482;1771.2737777511159;1192.48723049088;1220.3105245536422;1235.7193969452428;1414.2745850062906;1445.7108578284385;4574.428196475441;4728.13947561727;4879.706198316122;5120.692758434506;5279.102552566073;5222.503862133091;5442.755076021588;5530.866172434641;5557.861053473695;5731.589704126039;4439.200943830077;4818.349522814376;4984.675996060245;5137.717425541631;5171.8880440705425;2280.623697089995;2611;2628.6451329070333;2775.3894143288444;2983.5524671299477;5247.422783493813;5333.57331413486;5438.048341540215;5642.714700546286;5992.64044157352;4934.4572097069295;5276.34045744282;5674.914876276859;5854.121881717776;5945.821625351246;5319.574434045345;5744.018313681773;5749.540036797046;5903;5986.790462333797;5469.644473109235;5535.667859928417;5624.987502249634;5724.484796368347;5726.41674367505;4955.8017679292425;5208.348956380452;5386.468359870854;5514.711394771285;5610.438956104357;4892.165437960764;4970.110419153315;5590.888182236251;5966.134910437875;6010.248718910137;4083.8156934488666;4127.318992366226;4344.717593356421;4628.791704373244;4648.349231107715;5355.491228333302;5428.4842939920645;5572.331320241597;5699.886002279848;5767.711614419179;4874.707517548958;5122.641415100908;5278.076336640996;5523.696196709219;5585.497305242548;4426.734395936254;4713.599344055657;5082.8232354778265;5329.760160792851;5516.668999860021;5044.369453818273;5287.149497675783;5296.761645725875;5435.048866448276;5447.727613619224;4716.103940251342;5225.335079109605;5492.47821456961;5548.112302031655;5557.638378597269;4432.335149727567;4594.816207351669;4743.264793956942;5045.4450010498085;5422.861199148223 14:32:36 INFO - PROCESS | 1930 | |5;yui.html;716.3266529462302;721.9169795473522;756.2626439221756;811.7661155044333;828.950262984221;15336.23318834058;15690.921545392286;16028.919855400722;16032;16051;15339.156346400943;15403.84596154041;15527.922360388182;15586.064836109841;15590.844091559082;915.336381123685;947.3842002698233;953.5565961827749;954.6849539651898;955.126059655418;897.6005677473527;940.6002448959199;941.2846236859967;952.342883410448;957.7988622389272;999.9300048996573;1001.1240164855751;1001.9048190421898;1002.0280328081749;1004.1715584642662;982.8280050991084;993.5975929748463;994.9950250248775;995.4077323992213;996.8903420623726;733.3656387225066;736.8968344431784;740.0712106306551;741.0181509499935;741.3328004795673;15662.373505059784;15841.207939602973;16070.357185712557;16774.83225167757;16958.491245262663;14011.579652610337;14041.22773247471;14097.340911800939;14106.294685265799;14188.290585470788;11630.244034137753;11654.76690466186;11838.585649502225;11874.643760687193;12032.458539365749;3144.4497212987635;3582.3730847101915;3648.6372290240524;3666.981665091684;3709.536307961505;91672.0832791677;92237.53881230619;102604;120930.39534802358;123060.38469807594;10699.732506687395;10923.907609239172;11057.778844423066;11153.38656373891;11450.969412752893;21784.34646960594;21956.890215549138;22197.445063873532;22198.667019994715;22267.888660556593;6675.3110493026215;7967.163447838009;7990.240927111915;7997.400194985399;8138.511689298661;3942.3495123304906;4324.394584758136;4345.391645169679;4369.279068953587;4389.231884420214;3127.3276245607303;3458.8962331130106;3548.449990251515;3562.2697347043795;3567.2152126532123;647.090837373491;650.2587050762135;653.0400311542026;666.0774826864828;670.2191946382508;611.6941529235382;675.7397453748723;675.8479342147978;679.2799632389683;687.08274453604;9342.159205671522;9761.121499065119;10252;10318.380897146195;10331.586736530606;570.3441042800812;617.6417677746896;696.9686364113627;701.0885848397063;707.9416272672388;4701.78841952113;4957.628177886673;4959.752012399365;4962;4965.4289756678445;96629;99565.50217248868;100096;101640.98359016314;101681;83674;86872;87767;89005.55497222602;89313;61174.38825611776;66360.33639663638;70893.93659095118;71644.28355716376;72250.63874680593;9265.212456941235;9520.714378568655;9554.331196816156;9588.952055239959;9680;47424.2886356698;49001.01997959949;49054.264186036526;49113.508864910895;49674.75162624164;48708.5129148704;61722;64204.67897660482;64403;65043.69912601816;11025.502544308729;11138.721531961442;11177.385243811668;11190;11609.825852612048;12545.87454125447;12720.809187862003;12852.55016074433;12966.391130030268;13104.148230364995;11686.649400517997;11723.821584914502;11921.880781192078;12020.579279725167;12323.198992065845;11672.47473863695;11900.464479098287;12072.275663460221;12277.263364198177;12290.877091229331;11716.589919353122;11784.823227651763;11976.46105925218;12274;12297.631071067883;8368.748937531884;8370.916290837258;8562.143785621389;8747.212750852454;8781.604827782638;9207.76980575491;9728.416295022322;9944.701658950244;9956.253281003808;9958.900410995797 14:32:36 INFO - PROCESS | 1930 | __end_tp_report 14:32:36 INFO - PROCESS | 1930 | __start_cc_report 14:32:36 INFO - PROCESS | 1930 | _x_x_mozilla_cycle_collect,430 14:32:36 INFO - PROCESS | 1930 | __end_cc_report 14:32:36 INFO - PROCESS | 1930 | __startTimestamp1491082356406__endTimestamp 14:32:36 INFO - PROCESS | 1930 | 14:32:36 INFO - PROCESS | 1930 | ------- Summary: start ------- 14:32:36 INFO - PROCESS | 1930 | Number of tests: 6 14:32:36 INFO - PROCESS | 1930 | 14:32:36 INFO - PROCESS | 1930 | [#0] dojo.html Cycles:165 Average:5921.91 Median:6163.75 stddev:671.37 (10.9%) stddev-sans-first:634.63 14:32:36 INFO - PROCESS | 1930 | Values: 3054.5 3450.4 3456.2 3476.9 3496.9 4904.8 5895.4 6000.1 6000.4 6018.5 5987.6 6000.2 6004.4 6028.2 6055.0 4483.3 5372.4 5415.8 5466.3 5496.6 5495.1 5496.8 5503.6 5510.9 5522.4 5279.2 5290.4 5395.5 5501.6 5519.2 5401.7 5407.3 5420.3 5450.8 5482.6 5335.4 5443.1 5444.3 5482.5 5527.6 6045.8 6118.8 6120.4 6134.4 6158.4 6276.0 6281.4 6296.0 6314.2 6449.1 6440.6 6461.1 6472.5 6476.3 6481.5 6120.8 6146.6 6163.8 6179.9 6203.2 6262.7 6337.7 6356.1 6408.3 6581.2 6285.1 6286.2 6363.9 6379.0 6400.6 6403.1 6431.1 6440.4 6442.3 6456.1 6218.4 6225.6 6384.9 6409.1 6412.9 6265.9 6304.9 6360.8 6378.8 6405.0 5038.7 5074.1 5127.4 5166.8 5210.8 4601.9 4640.4 4640.6 4652.1 4685.0 5989.6 6055.6 6075.1 6081.5 6085.9 6279.2 6315.7 6324.8 6330.3 6444.6 6064.1 6073.5 6073.8 6082.0 6100.9 5684.5 5685.1 5692.8 5697.0 5704.0 6306.1 6333.3 6468.0 6612.7 6649.2 6368.2 6375.6 6434.9 6442.7 6446.0 5448.7 6555.1 6573.2 6594.1 6621.9 6198.5 6243.8 6375.9 6398.0 6399.9 6348.5 6375.9 6404.5 6441.2 6443.5 6311.4 6344.1 6351.5 6361.1 6379.6 6117.7 6302.6 6352.9 6361.2 6389.3 4991.6 5003.0 5016.7 5609.5 6135.7 6285.6 6312.6 6315.0 6324.4 6329.1 5866.1 6058.4 6073.5 6232.8 6237.6 14:32:36 INFO - PROCESS | 1930 | 14:32:36 INFO - PROCESS | 1930 | [#1] ext.html Cycles:180 Average:54073.60 Median:27669.88 stddev:111233.67 (402.0%) stddev-sans-first:111473.42 14:32:36 INFO - PROCESS | 1930 | Values: 660.7 808.2 956.3 964.5 979.4 37750.6 39332.6 39387.8 39430.0 39436.4 38048.0 38156.2 38156.7 38272.4 38390.0 7771.5 10493.5 10780.0 10939.3 10955.0 10679.8 10752.9 10758.7 10793.9 10811.7 10773.2 10788.8 10822.4 10829.8 10839.9 10763.4 10775.4 10790.5 10795.8 10796.5 10544.5 10594.7 10606.0 10608.8 10617.0 33352.5 33416.7 33535.8 33703.0 33810.0 3706.3 3764.9 3777.5 3830.5 3833.2 25185.6 25466.0 25867.5 25971.4 26224.7 3004.9 3031.1 3034.0 3051.5 3052.4 330647.3 331371.0 332027.0 332899.7 332922.0 45146.3 45218.1 45223.0 45347.5 45389.1 46583.5 46855.5 46916.8 47133.8 47135.0 13854.4 13857.6 13983.5 13991.3 14001.3 11886.8 12029.4 12096.3 12104.0 12111.9 8331.9 11384.1 11925.7 11955.8 12043.8 5710.4 5812.3 5815.7 5822.6 5826.5 937.2 1070.9 1077.8 1093.9 1122.4 19188.8 21095.7 23216.0 25221.0 25720.0 1145.9 1158.8 1204.3 1237.2 1255.4 10975.3 13418.7 13420.3 13689.7 13736.0 459293.0 459297.0 460455.0 461772.0 465839.0 55813.0 55860.4 56259.4 56266.0 56546.0 1396.4 1446.7 1446.7 1452.5 1456.3 25525.7 25804.9 25901.8 26364.1 26472.2 45965.1 46000.0 46144.8 46456.8 46915.0 446377.0 447408.0 449589.8 449998.0 455218.0 30940.2 31478.1 31540.7 31560.4 31663.0 37329.8 39392.0 43060.4 43303.4 43376.3 26422.1 26987.0 27655.3 28680.9 28774.0 23406.6 24981.9 26886.7 27781.9 28750.1 26781.0 26836.0 27260.7 27380.6 27465.2 21914.9 27287.0 27358.7 27684.4 28618.3 21310.6 22129.6 22450.6 23307.4 23373.9 14:32:36 INFO - PROCESS | 1930 | 14:32:36 INFO - PROCESS | 1930 | [#2] jquery.html Cycles:180 Average:39961.74 Median:11706.53 stddev:109135.43 (932.3%) stddev-sans-first:109401.14 14:32:36 INFO - PROCESS | 1930 | Values: 391.4 404.1 408.8 409.8 433.8 19710.9 20250.9 20619.7 20979.1 21213.0 21733.8 21746.9 22056.6 22732.2 22912.3 6816.1 7027.2 7038.4 7090.5 7100.0 6939.2 7350.6 7714.5 7879.1 7880.8 6438.6 7290.3 7616.2 7804.1 7866.2 6402.4 7607.7 7625.0 7625.9 7693.2 6757.7 6994.9 7200.3 7421.5 7467.7 25114.5 25549.6 26395.3 26607.9 26614.5 2625.7 2818.0 2850.2 2900.9 2924.4 16329.6 16410.5 16923.4 16981.3 17267.8 2049.1 2082.0 2088.7 2100.7 2129.4 130352.0 158676.0 172095.0 173632.0 175558.0 14643.1 19751.9 19893.8 21915.9 22406.0 32769.0 33152.8 33593.8 33794.3 33882.7 10357.9 10474.9 10721.4 10796.2 10874.8 8928.3 8938.1 8953.7 9084.6 9097.9 9835.9 9850.7 9883.5 10037.5 10050.6 4121.1 4170.5 4174.0 4198.8 4206.3 989.9 1001.0 1001.7 1005.3 1043.4 20828.8 21072.2 21208.6 21283.7 21408.0 881.4 981.5 993.6 1027.7 1029.9 10097.9 10897.7 10996.3 11028.0 11037.9 434320.0 498922.0 678953.0 758371.0 787944.0 115528.0 122710.0 133756.3 157731.0 168822.0 1186.7 1261.9 1321.6 1324.2 1335.8 15993.5 19021.1 19628.5 19873.4 20471.0 49435.8 65307.7 65893.0 67334.7 68827.0 90400.0 90580.0 93315.1 93683.1 95196.5 9764.5 11689.0 11743.7 11804.1 11960.6 13147.2 13305.8 13398.4 13500.1 13525.9 9742.3 10996.7 11326.0 11378.9 11485.7 10244.2 10453.1 10560.7 10741.2 10784.7 11317.8 11334.1 11480.8 11581.7 11648.8 11591.9 11643.4 11714.4 11987.9 12018.9 9543.2 11262.8 11267.4 11305.6 11310.8 14:32:36 INFO - PROCESS | 1930 | 14:32:36 INFO - PROCESS | 1930 | [#3] mootools.html Cycles:180 Average:6788.15 Median:2453.55 stddev:4310.97 (175.7%) stddev-sans-first:4303.42 14:32:36 INFO - PROCESS | 1930 | Values: 1311.6 1369.5 1467.2 1523.1 1561.0 10891.6 11091.6 11435.6 11453.8 11562.6 10945.3 11168.0 11240.3 11249.8 11269.0 2405.5 2453.1 2454.0 2456.3 2465.1 2343.4 2429.6 2440.4 2443.5 2449.9 2364.5 2417.3 2425.9 2431.2 2434.1 2279.7 2402.7 2408.4 2419.2 2434.4 2035.9 2113.9 2328.2 2427.4 2438.1 11195.8 11275.1 11283.4 11307.7 11326.3 5875.7 6025.9 6091.4 6091.6 6096.9 3616.1 3703.4 3763.9 3784.9 3830.4 1164.0 1202.3 1224.5 1248.2 1270.9 15716.0 15803.8 15843.2 15989.7 16059.0 4733.4 4802.7 4833.7 4842.4 4861.5 11844.2 12043.9 12053.6 12059.1 12116.8 5809.5 5887.0 5893.4 5898.5 5924.6 5062.2 5320.8 5324.2 5339.1 5364.3 3840.5 3949.8 3984.7 4045.6 4049.9 1065.9 1068.7 1071.4 1083.8 1087.9 910.6 914.4 929.3 934.6 979.5 6373.6 6383.8 6441.2 6559.4 6632.3 925.1 933.4 944.2 971.6 981.5 3710.3 3930.9 4078.3 4139.4 4285.3 13484.5 14026.5 14029.2 14050.3 14084.5 13565.4 13813.3 13859.7 13962.9 13981.3 9894.1 10149.2 10255.2 10260.7 10282.9 4834.6 6507.8 6570.7 6614.6 6617.3 12125.6 12279.6 12291.9 12305.6 12316.8 12365.5 12581.4 12639.5 12816.9 12821.2 8500.9 8504.6 8548.4 8611.9 8639.1 10315.3 10357.8 10372.6 10374.4 10442.2 8893.0 8906.0 8981.7 8997.1 9139.1 8533.7 8713.1 8725.1 8793.4 8899.0 7497.4 7759.7 7886.8 7893.8 7897.8 5281.7 5630.3 5838.8 5840.5 5853.2 5070.6 5725.3 5776.8 5980.8 6043.9 14:32:36 INFO - PROCESS | 1930 | 14:32:36 INFO - PROCESS | 1930 | [#4] prototype.html Cycles:180 Average:4587.90 Median:4986.74 stddev:1266.57 (25.4%) stddev-sans-first:1229.10 14:32:36 INFO - PROCESS | 1930 | Values: 328.1 370.9 380.5 385.3 389.7 4679.3 4960.2 5237.6 5277.3 5359.8 4569.5 4928.7 5077.2 5160.7 5175.7 4095.2 4189.6 4281.3 4282.1 4284.8 3498.8 4084.7 4247.9 4291.6 4316.5 3512.0 4304.5 4322.2 4323.7 4331.0 3475.4 3649.9 4003.7 4272.3 4295.7 3583.2 3671.1 3876.1 3912.6 3931.7 4067.6 4270.5 5018.9 5053.6 5058.3 4096.4 4506.2 4556.6 4731.0 4988.8 4816.9 5039.9 5276.1 5515.9 5531.5 4140.4 4439.9 4443.9 4467.0 4486.9 4718.4 5004.8 5150.7 5884.8 5900.6 4313.4 4646.1 4822.9 5029.9 5050.0 5125.3 5152.5 5159.7 5176.7 5193.4 4671.8 4765.6 4798.3 4977.9 5264.1 5560.4 5567.9 5582.7 5624.0 5652.5 1491.8 1532.1 1634.4 1736.4 1771.3 1192.5 1220.3 1235.7 1414.3 1445.7 4574.4 4728.1 4879.7 5120.7 5279.1 5222.5 5442.8 5530.9 5557.9 5731.6 4439.2 4818.3 4984.7 5137.7 5171.9 2280.6 2611.0 2628.6 2775.4 2983.6 5247.4 5333.6 5438.0 5642.7 5992.6 4934.5 5276.3 5674.9 5854.1 5945.8 5319.6 5744.0 5749.5 5903.0 5986.8 5469.6 5535.7 5625.0 5724.5 5726.4 4955.8 5208.3 5386.5 5514.7 5610.4 4892.2 4970.1 5590.9 5966.1 6010.2 4083.8 4127.3 4344.7 4628.8 4648.3 5355.5 5428.5 5572.3 5699.9 5767.7 4874.7 5122.6 5278.1 5523.7 5585.5 4426.7 4713.6 5082.8 5329.8 5516.7 5044.4 5287.1 5296.8 5435.0 5447.7 4716.1 5225.3 5492.5 5548.1 5557.6 4432.3 4594.8 4743.3 5045.4 5422.9 14:32:36 INFO - PROCESS | 1930 | 14:32:36 INFO - PROCESS | 1930 | [#5] yui.html Cycles:180 Average:19647.35 Median:48854.77 stddev:28417.58 (58.2%) stddev-sans-first:28461.75 14:32:36 INFO - PROCESS | 1930 | Values: 716.3 721.9 756.3 811.8 829.0 15336.2 15690.9 16028.9 16032.0 16051.0 15339.2 15403.8 15527.9 15586.1 15590.8 915.3 947.4 953.6 954.7 955.1 897.6 940.6 941.3 952.3 957.8 999.9 1001.1 1001.9 1002.0 1004.2 982.8 993.6 995.0 995.4 996.9 733.4 736.9 740.1 741.0 741.3 15662.4 15841.2 16070.4 16774.8 16958.5 14011.6 14041.2 14097.3 14106.3 14188.3 11630.2 11654.8 11838.6 11874.6 12032.5 3144.4 3582.4 3648.6 3667.0 3709.5 91672.1 92237.5 102604.0 120930.4 123060.4 10699.7 10923.9 11057.8 11153.4 11451.0 21784.3 21956.9 22197.4 22198.7 22267.9 6675.3 7967.2 7990.2 7997.4 8138.5 3942.3 4324.4 4345.4 4369.3 4389.2 3127.3 3458.9 3548.4 3562.3 3567.2 647.1 650.3 653.0 666.1 670.2 611.7 675.7 675.8 679.3 687.1 9342.2 9761.1 10252.0 10318.4 10331.6 570.3 617.6 697.0 701.1 707.9 4701.8 4957.6 4959.8 4962.0 4965.4 96629.0 99565.5 100096.0 101641.0 101681.0 83674.0 86872.0 87767.0 89005.6 89313.0 61174.4 66360.3 70893.9 71644.3 72250.6 9265.2 9520.7 9554.3 9589.0 9680.0 47424.3 49001.0 49054.3 49113.5 49674.8 48708.5 61722.0 64204.7 64403.0 65043.7 11025.5 11138.7 11177.4 11190.0 11609.8 12545.9 12720.8 12852.6 12966.4 13104.1 11686.6 11723.8 11921.9 12020.6 12323.2 11672.5 11900.5 12072.3 12277.3 12290.9 11716.6 11784.8 11976.5 12274.0 12297.6 8368.7 8370.9 8562.1 8747.2 8781.6 9207.8 9728.4 9944.7 9956.3 9958.9 14:32:36 INFO - PROCESS | 1930 | -------- Summary: end -------- 14:32:36 INFO - PROCESS | 1930 | 14:32:37 INFO - TEST-INFO | 1930: exit 0 14:32:37 INFO - TEST-OK | dromaeo_css | took 1116292ms 14:32:37 INFO - TEST-START | kraken 14:32:37 INFO - operating with platform_type : mac_ 14:32:37 INFO - Initialising browser for kraken test... 14:32:37 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpo_rfOL/profile http://localhost:52955/getInfo.html) 14:32:39 INFO - PROCESS | 1936 | 2017-04-01 14:32:39.909 plugin-container[1937:19983] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 14:32:39 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:32:39 INFO - PROCESS | 1936 | 2017-04-01 14:32:39.912 plugin-container[1937:19983] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 14:32:39 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:32:39 INFO - PROCESS | 1936 | 2017-04-01 14:32:39.912 plugin-container[1937:19983] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:32:39 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 14:32:39 INFO - PROCESS | 1936 | colorDepth:24 14:32:39 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 14:32:39 INFO - PROCESS | 1936 | __metrics 14:32:40 ERROR - PROCESS | 1936 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 14:32:40 INFO - TEST-INFO | 1936: exit 0 14:32:40 INFO - Browser initialized. 14:32:40 INFO - Running cycle 1/1 for kraken test... 14:32:40 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpo_rfOL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:32:43 INFO - PROCESS | 1938 | 2017-04-01 14:32:43.152 plugin-container[1939:20107] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 14:32:43 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:32:43 INFO - PROCESS | 1938 | 2017-04-01 14:32:43.152 plugin-container[1939:20107] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 14:32:43 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:32:43 INFO - PROCESS | 1938 | 2017-04-01 14:32:43.152 plugin-container[1939:20107] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:32:52 INFO - PROCESS | 1938 | RSS: Main: 220700672 14:32:52 INFO - PROCESS | 1938 | 14:32:56 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52955/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52955/tests/kraken/driver.html?testName=audio-beat-detection) 14:32:57 INFO - PROCESS | 1938 | RSS: Main: 235442176 14:32:57 INFO - PROCESS | 1938 | 14:32:59 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52955/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52955/tests/kraken/driver.html?testName=audio-dft) 14:33:00 INFO - PROCESS | 1938 | RSS: Main: 243359744 14:33:00 INFO - PROCESS | 1938 | 14:33:03 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52955/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52955/tests/kraken/driver.html?testName=audio-fft) 14:33:04 INFO - PROCESS | 1938 | RSS: Main: 247050240 14:33:04 INFO - PROCESS | 1938 | 14:33:06 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52955/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52955/tests/kraken/driver.html?testName=audio-oscillator) 14:33:06 INFO - PROCESS | 1938 | RSS: Main: 248475648 14:33:06 INFO - PROCESS | 1938 | 14:33:08 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52955/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52955/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:33:09 INFO - PROCESS | 1938 | RSS: Main: 248504320 14:33:09 INFO - PROCESS | 1938 | 14:33:12 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52955/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52955/tests/kraken/driver.html?testName=imaging-darkroom) 14:33:13 INFO - PROCESS | 1938 | RSS: Main: 252579840 14:33:13 INFO - PROCESS | 1938 | 14:33:16 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52955/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52955/tests/kraken/driver.html?testName=imaging-desaturate) 14:33:17 INFO - PROCESS | 1938 | RSS: Main: 252211200 14:33:17 INFO - PROCESS | 1938 | 14:33:20 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52955/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52955/tests/kraken/driver.html?testName=json-parse-financial) 14:33:21 INFO - PROCESS | 1938 | RSS: Main: 252116992 14:33:21 INFO - PROCESS | 1938 | 14:33:22 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52955/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52955/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:33:23 INFO - PROCESS | 1938 | RSS: Main: 251740160 14:33:23 INFO - PROCESS | 1938 | 14:33:25 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52955/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52955/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:33:25 INFO - PROCESS | 1938 | RSS: Main: 251723776 14:33:25 INFO - PROCESS | 1938 | 14:33:28 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52955/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52955/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:33:28 INFO - PROCESS | 1938 | RSS: Main: 251813888 14:33:28 INFO - PROCESS | 1938 | 14:33:31 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52955/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52955/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:33:32 INFO - PROCESS | 1938 | RSS: Main: 252452864 14:33:32 INFO - PROCESS | 1938 | 14:33:35 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52955/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52955/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:33:36 INFO - PROCESS | 1938 | RSS: Main: 251957248 14:33:36 INFO - PROCESS | 1938 | 14:33:37 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52955/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52955/tests/kraken/driver.html?testName=ai-astar) 14:33:38 INFO - PROCESS | 1938 | RSS: Main: 253890560 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | __start_tp_report 14:33:38 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 14:33:38 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 14:33:38 INFO - PROCESS | 1938 | |i|pagename|runs| 14:33:38 INFO - PROCESS | 1938 | |0;ai-astar;108;121;133;162;120;119;120;120;138;178 14:33:38 INFO - PROCESS | 1938 | |1;audio-beat-detection;171;108;112;145;116;113;114;113;142;113 14:33:38 INFO - PROCESS | 1938 | |2;audio-dft;199;198;198;198;198;199;197;196;199;193 14:33:38 INFO - PROCESS | 1938 | |3;audio-fft;81;52;71;114;56;81;92;90;99;88 14:33:38 INFO - PROCESS | 1938 | |4;audio-oscillator;89;95;88;70;72;91;116;106;81;82 14:33:38 INFO - PROCESS | 1938 | |5;imaging-gaussian-blur;89;89;88;89;102;89;85;135;88;89 14:33:38 INFO - PROCESS | 1938 | |6;imaging-darkroom;96;97;97;112;112;92;157;93;155;155 14:33:38 INFO - PROCESS | 1938 | |7;imaging-desaturate;129;100;94;149;103;74;145;143;113;138 14:33:38 INFO - PROCESS | 1938 | |8;json-parse-financial;65;64;64;69;72;68;68;69;68;68 14:33:38 INFO - PROCESS | 1938 | |9;json-stringify-tinderbox;56;56;63;67;61;58;61;72;74;51 14:33:38 INFO - PROCESS | 1938 | |10;stanford-crypto-aes;85;87;73;95;115;102;105;95;84;84 14:33:38 INFO - PROCESS | 1938 | |11;stanford-crypto-ccm;142;140;169;152;158;147;166;170;199;161 14:33:38 INFO - PROCESS | 1938 | |12;stanford-crypto-pbkdf2;180;194;186;233;177;155;161;148;146;223 14:33:38 INFO - PROCESS | 1938 | |13;stanford-crypto-sha256-iterative;69;67;67;64;67;66;67;65;63;65 14:33:38 INFO - PROCESS | 1938 | __end_tp_report 14:33:38 INFO - PROCESS | 1938 | __start_cc_report 14:33:38 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,1026 14:33:38 INFO - PROCESS | 1938 | __end_cc_report 14:33:38 INFO - PROCESS | 1938 | __startTimestamp1491082418074__endTimestamp 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | ------- Summary: start ------- 14:33:38 INFO - PROCESS | 1938 | Number of tests: 14 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | [#0] ai-astar Cycles:10 Average:131.90 Median:127.00 stddev:21.99 (17.3%) stddev-sans-first:21.55 14:33:38 INFO - PROCESS | 1938 | Values: 108.0 121.0 133.0 162.0 120.0 119.0 120.0 120.0 138.0 178.0 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | [#1] audio-beat-detection Cycles:10 Average:124.70 Median:115.00 stddev:20.81 (18.1%) stddev-sans-first:13.76 14:33:38 INFO - PROCESS | 1938 | Values: 171.0 108.0 112.0 145.0 116.0 113.0 114.0 113.0 142.0 113.0 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | [#2] audio-dft Cycles:10 Average:197.50 Median:198.00 stddev:1.84 (0.9%) stddev-sans-first:1.87 14:33:38 INFO - PROCESS | 1938 | Values: 199.0 198.0 198.0 198.0 198.0 199.0 197.0 196.0 199.0 193.0 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | [#3] audio-fft Cycles:10 Average:82.40 Median:84.50 stddev:18.89 (22.4%) stddev-sans-first:20.03 14:33:38 INFO - PROCESS | 1938 | Values: 81.0 52.0 71.0 114.0 56.0 81.0 92.0 90.0 99.0 88.0 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:85.00 stddev:14.23 (16.7%) stddev-sans-first:15.09 14:33:38 INFO - PROCESS | 1938 | Values: 89.0 95.0 88.0 70.0 72.0 91.0 116.0 106.0 81.0 82.0 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | [#5] imaging-gaussian-blur Cycles:10 Average:94.30 Median:89.00 stddev:14.99 (16.8%) stddev-sans-first:15.78 14:33:38 INFO - PROCESS | 1938 | Values: 89.0 89.0 88.0 89.0 102.0 89.0 85.0 135.0 88.0 89.0 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | [#6] imaging-darkroom Cycles:10 Average:116.60 Median:92.50 stddev:27.85 (30.1%) stddev-sans-first:28.52 14:33:38 INFO - PROCESS | 1938 | Values: 96.0 97.0 97.0 112.0 112.0 92.0 157.0 93.0 155.0 155.0 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | [#7] imaging-desaturate Cycles:10 Average:118.80 Median:144.00 stddev:25.64 (17.8%) stddev-sans-first:26.93 14:33:38 INFO - PROCESS | 1938 | Values: 129.0 100.0 94.0 149.0 103.0 74.0 145.0 143.0 113.0 138.0 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | [#8] json-parse-financial Cycles:10 Average:67.50 Median:68.00 stddev:2.51 (3.7%) stddev-sans-first:2.49 14:33:38 INFO - PROCESS | 1938 | Values: 65.0 64.0 64.0 69.0 72.0 68.0 68.0 69.0 68.0 68.0 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | [#9] json-stringify-tinderbox Cycles:10 Average:61.90 Median:62.00 stddev:7.31 (11.8%) stddev-sans-first:7.43 14:33:38 INFO - PROCESS | 1938 | Values: 56.0 56.0 63.0 67.0 61.0 58.0 61.0 72.0 74.0 51.0 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | [#10] stanford-crypto-aes Cycles:10 Average:92.50 Median:84.50 stddev:12.37 (14.6%) stddev-sans-first:12.82 14:33:38 INFO - PROCESS | 1938 | Values: 85.0 87.0 73.0 95.0 115.0 102.0 105.0 95.0 84.0 84.0 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | [#11] stanford-crypto-ccm Cycles:10 Average:160.40 Median:163.50 stddev:17.32 (10.6%) stddev-sans-first:17.04 14:33:38 INFO - PROCESS | 1938 | Values: 142.0 140.0 169.0 152.0 158.0 147.0 166.0 170.0 199.0 161.0 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.30 Median:183.00 stddev:29.93 (16.4%) stddev-sans-first:31.75 14:33:38 INFO - PROCESS | 1938 | Values: 180.0 194.0 186.0 233.0 177.0 155.0 161.0 148.0 146.0 223.0 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - PROCESS | 1938 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:67.00 stddev:1.76 (2.6%) stddev-sans-first:1.50 14:33:38 INFO - PROCESS | 1938 | Values: 69.0 67.0 67.0 64.0 67.0 66.0 67.0 65.0 63.0 65.0 14:33:38 INFO - PROCESS | 1938 | -------- Summary: end -------- 14:33:38 INFO - PROCESS | 1938 | 14:33:38 INFO - TEST-INFO | 1938: exit 0 14:33:39 INFO - TEST-OK | kraken | took 61957ms 14:33:39 INFO - SUITE-END | took 1178s 14:33:39 INFO - Completed test suite (00:19:38) 14:33:39 INFO - javascript benchmark 14:33:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7644.470014436538, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3054.5418187271894, 3450.4479283314668, 3456.170519075423, 3476.947845782314, 3496.930061398771, 4904.828331008411, 5895.439933206344, 6000.069989151678, 6000.3699611540815, 6018.488428483573, 5987.580869339148, 6000.189974353461, 6004.399560043993, 6028.21633187685, 6055.0, 4483.34991426243, 5372.382176049756, 5415.810446634368, 5466.34403871534, 5496.587755918322, 5495.093309603911, 5496.7526461309335, 5503.6147469677135, 5510.889782204354, 5522.447755224465, 5279.234510995903, 5290.365156181264, 5395.541378982791, 5501.559875209973, 5519.172124181365, 5401.70290634015, 5407.324084489419, 5420.322459692538, 5450.754716037788, 5482.5613950883835, 5335.359756829205, 5443.129099344125, 5444.292242008553, 5482.506574408282, 5527.585431092684, 6045.818625441244, 6118.847028824315, 6120.357362476965, 6134.447899689049, 6158.414950579688, 6275.96862015687, 6281.403266689657, 6295.968520157416, 6314.242290925118, 6449.06488559161, 6440.581362211488, 6461.12774775404, 6472.482201423874, 6476.287608363076, 6481.546291759579, 6120.755169793248, 6146.569740118193, 6163.753449862, 6179.876402471926, 6203.2245969253845, 6262.749490020348, 6337.651429171347, 6356.078368636522, 6408.327125651833, 6581.177352830896, 6285.057241413733, 6286.151369565142, 6363.936360636334, 6379.0112532556595, 6400.615963042233, 6403.103565500834, 6431.067495213168, 6440.388163124496, 6442.259140198843, 6456.063870738717, 6218.409251121137, 6225.595336303126, 6384.936150638434, 6409.134766806515, 6412.871742565123, 6265.937340626627, 6304.936950630527, 6360.840978975562, 6378.840528986812, 6405.039244113327, 5038.672486288379, 5074.061298659686, 5127.384713834289, 5166.844994650167, 5210.817621383309, 4601.861944141681, 4640.443146822403, 4640.628749700016, 4652.069586082797, 4685.01614660925, 5989.580729348903, 6055.6063855849225, 6075.149479072933, 6081.483073938764, 6085.908711369333, 6279.215098112736, 6315.747370105236, 6324.841878953063, 6330.303666596671, 6444.581102228341, 6064.120702498113, 6073.51411887048, 6073.75704971805, 6081.969590152109, 6100.908486372708, 5684.54523638108, 5685.118806584986, 5692.800751973661, 5697.0, 5703.971480142573, 6306.148669929501, 6333.303336632875, 6468.0, 6612.669366531751, 6649.168853893263, 6368.172137622079, 6375.649339286384, 6434.935650643433, 6442.710078046405, 6446.0, 5448.673079615077, 6555.115059466911, 6573.1783527059115, 6594.142761440982, 6621.9337806621315, 6198.535109866688, 6243.843903902439, 6375.904361434675, 6398.0402939559435, 6399.936000639934, 6348.492120630431, 6375.9362406377195, 6404.487640988803, 6441.1626488556185, 6443.548951573345, 6311.368863113652, 6344.080108384351, 6351.523635727432, 6361.109444677842, 6379.649119298483, 6117.705611546597, 6302.621842689453, 6352.872942541031, 6361.2048493938255, 6389.329120442287, 4991.575716064175, 5002.974985125051, 5016.724080175625, 5609.49514543693, 6135.6932153393045, 6285.5600107992, 6312.589681670676, 6314.968425157845, 6324.3991820776955, 6329.1139240505445, 5866.090755932837, 6058.4244496772735, 6073.537438594785, 6232.844178895564, 6237.5633705641985], "value": 5878.771470923877, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.6828722213337, 808.2321794295419, 956.2732323434188, 964.5177411294353, 979.4025644356941, 37750.62249377505, 39332.60667393329, 39387.80306098473, 39430.01424964378, 39436.40845387321, 38048.0, 38156.236875262475, 38156.66451674192, 38272.42591361132, 38390.0, 7771.494852834548, 10493.475326233696, 10780.0, 10939.343639381623, 10954.959278868495, 10679.83980240297, 10752.892471075267, 10758.731031724192, 10793.892061079367, 10811.729706757314, 10773.192010599198, 10788.838167427495, 10822.404767737771, 10829.783404331909, 10839.891601084006, 10763.408012559306, 10775.407352595605, 10790.460476976119, 10795.78408431831, 10796.51415686296, 10544.472776361228, 10594.735131621694, 10606.0, 10608.840867386996, 10617.0, 33352.49971250433, 33416.6658333416, 33535.83232083848, 33702.98891033273, 33810.0, 3706.2772759311947, 3764.887053388403, 3777.508923839883, 3830.4637350770636, 3833.2141910908476, 25185.62221566678, 25466.0, 25867.482650347076, 25971.350716232057, 26224.73775262242, 3004.8948286809855, 3031.10582378199, 3033.9848300758576, 3051.450738867003, 3052.3895220955897, 330647.34676326707, 331371.0, 332027.0, 332899.6710032893, 332922.0, 45146.32280515795, 45218.095638087056, 45223.0, 45347.54652453433, 45389.09221815545, 46583.534164658595, 46855.531444685796, 46916.765416172704, 47133.76433117812, 47135.0, 13854.376553055137, 13857.584272471842, 13983.510577129751, 13991.300434978413, 14001.29993500321, 11886.762264754656, 12029.398530073462, 12096.27422354644, 12104.0, 12111.87888121125, 8331.875021874677, 11384.089272858317, 11925.701857453632, 11955.76088478243, 12043.81934270969, 5710.371859095496, 5812.3025236971835, 5815.73829177688, 5822.592418530747, 5826.533877289807, 937.2033771294346, 1070.8661417322835, 1077.7828182874114, 1093.8842380771728, 1122.4107343644653, 19188.80811191898, 21095.68356474654, 23216.0, 25221.0, 25720.0, 1145.931244125355, 1158.8030034894089, 1204.2894692131683, 1237.2205510528308, 1255.4350542256022, 10975.341479511255, 13418.731625367438, 13420.26188559619, 13689.726205475834, 13736.038477306492, 459293.0, 459297.0, 460455.0, 461772.0, 465839.0, 55813.0, 55860.44139558553, 56259.43740562624, 56266.0, 56546.0, 1396.406527225925, 1446.7323545144181, 1446.7395868743727, 1452.4771082410114, 1456.2936975566843, 25525.744742552335, 25804.870975645, 25901.834417451635, 26364.077257295903, 26472.205833825017, 45965.08069838651, 46000.0, 46144.769276153405, 46456.7677161612, 46915.0, 446377.0, 447408.0, 449589.7520512376, 449998.0, 455218.0, 30940.22649433782, 31478.055658330286, 31540.684593153775, 31560.36879262448, 31663.0, 37329.81335093416, 39392.0, 43060.35409468923, 43303.35044974265, 43376.349354760336, 26422.07522736695, 26987.0, 27655.308617283925, 28680.85276588913, 28773.992910248042, 23406.64890026685, 24981.875815588704, 26886.655667216954, 27781.861090694416, 28750.13749587516, 26781.0, 26836.0, 27260.727392725817, 27380.58929116025, 27465.17604471869, 21914.890425547772, 27287.044953426535, 27358.726412735617, 27684.44631107326, 28618.284542886595, 21310.6148100373, 22129.557408852055, 22450.5509889798, 23307.417314567272, 23373.88313058424], "value": 18012.432144437753, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.4070183671737, 404.14321638127166, 408.77721641705284, 409.75414751149293, 433.77877282585894, 19710.90144549277, 20250.89874550629, 20619.706631393987, 20979.055942482588, 21213.0, 21733.782662173373, 21746.89126554369, 22056.558868822653, 22732.204372846954, 22912.312630621065, 6816.113905192318, 7027.191872934627, 7038.401735852459, 7090.468214883878, 7099.964500177518, 6939.23668396476, 7350.558966462003, 7714.537127772352, 7879.054513458393, 7880.80297992549, 6438.645874476902, 7290.270972902694, 7616.238376162395, 7804.102528209243, 7866.213378662145, 6402.359764023584, 7607.733729319502, 7625.0, 7625.923740762577, 7693.192214817447, 6757.662116894185, 6994.91578805286, 7200.315969982843, 7421.480496365257, 7467.738629147953, 25114.49771004588, 25549.616755748677, 26395.34011649705, 26607.935682572675, 26614.467710645873, 2625.737426257359, 2817.971820281791, 2850.2446851584345, 2900.941981160386, 2924.4443555724342, 16329.59176020597, 16410.507684769476, 16923.392277733594, 16981.3207471701, 17267.82732172675, 2049.067674208232, 2082.0, 2088.6866969954476, 2100.653392190287, 2129.371835308588, 130352.0, 158676.0, 172095.0, 173632.0, 175558.0, 14643.140107932506, 19751.901240493993, 19893.801061989485, 21915.890420547796, 22406.0, 32769.0, 33152.83965061211, 33593.83203083969, 33794.32411351759, 33882.66117338795, 10357.896421035844, 10474.895251047392, 10721.445390418501, 10796.190285728602, 10874.782504349869, 8928.330375221884, 8938.10618938114, 8953.686620968365, 9084.591193396313, 9097.862767154105, 9835.852462213072, 9850.704478865646, 9883.45640989738, 10037.548310325907, 10050.587858913808, 4121.083696081277, 4170.541240463546, 4173.9582604174175, 4198.832046718158, 4206.348016057516, 989.9406035637885, 1000.9949950250348, 1001.7044971733357, 1005.3415013166388, 1043.3844032020993, 20828.791712082988, 21072.208862246574, 21208.575828483652, 21283.680744788842, 21408.0, 881.4402854187509, 981.455292312759, 993.6373223773415, 1027.6505987964129, 1029.8928651699453, 10097.899021009696, 10897.727556810985, 10996.340219586851, 11028.0, 11037.944810275898, 434320.0, 498922.0, 678953.0, 758371.0, 787944.0, 115528.0, 122710.0, 133756.3312183433, 157731.0, 168822.0, 1186.679596508955, 1261.9116661833766, 1321.6035189443128, 1324.218710960538, 1335.826342575459, 15993.520194394187, 19021.144048517574, 19628.509287267934, 19873.403797886087, 20471.0, 49435.76410589764, 65307.69384612186, 65893.0, 67334.66332668305, 68827.0, 90400.0, 90580.0, 93315.13369732704, 93683.06316936744, 95196.52401737947, 9764.511774411108, 11689.0, 11743.706407339885, 11804.114691398008, 11960.641180764243, 13147.211167329991, 13305.80041299362, 13398.39219293652, 13500.122492037986, 13525.917926565655, 9742.269329800152, 10996.670099897015, 11326.037286830711, 11378.924045906311, 11485.712857178638, 10244.231682623982, 10453.059224669816, 10560.674543651474, 10741.248112632353, 10784.730381740519, 11317.830232546683, 11334.14993875446, 11480.827787583026, 11581.652550423163, 11648.76702465929, 11591.942040289745, 11643.41782910834, 11714.414279286171, 11987.940060299643, 12018.879811201776, 9543.18882894934, 11262.774744505228, 11267.436628168723, 11305.604303849326, 11310.773784524099], "value": 11739.279684937374, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1311.626186536837, 1369.4659082957646, 1467.1930438258955, 1523.0937592132677, 1560.9697599584276, 10891.618793342235, 11091.5563377465, 11435.599754008612, 11453.770924581524, 11562.59530916418, 10945.288556243839, 11167.994880460757, 11240.325580465178, 11249.775004499905, 11269.0, 2405.470796424788, 2453.092355828337, 2454.006127518358, 2456.349067497106, 2465.1125594785863, 2343.4024323797407, 2429.574824405731, 2440.3533063738123, 2443.51129774045, 2449.9142530011454, 2364.5034542746043, 2417.3231495181376, 2425.9393515162174, 2431.1612493689736, 2434.087217293515, 2279.7150356205475, 2402.6636270922086, 2408.397900524869, 2419.2379400488967, 2434.379233295498, 2035.8880261585607, 2113.883736394513, 2328.161861729776, 2427.4052857050137, 2438.061346381638, 11195.776084478264, 11275.097992160607, 11283.43582820864, 11307.717307067307, 11326.32042077478, 5875.706214689291, 6025.879482410371, 6091.390860913917, 6091.604045737013, 6096.908546371808, 3616.0959760059986, 3703.407454807218, 3763.9059023524633, 3784.8864534064023, 3830.425436184567, 1163.97090072748, 1202.326697049655, 1224.4857159992825, 1248.182440501473, 1270.8665590112998, 15716.0, 15803.841961580467, 15843.207839607974, 15989.680206395808, 16059.036457812568, 4733.431988161374, 4802.711837289774, 4833.709977401367, 4842.443119041285, 4861.51384861511, 11844.230125041844, 12043.939780301218, 12053.57812476559, 12059.095567832448, 12116.81824772629, 5809.535237181015, 5887.0, 5893.381194974551, 5898.528117750569, 5924.6149000314845, 5062.164742817467, 5320.76056577455, 5324.1747529133045, 5339.119045357473, 5364.30264065669, 3840.519935008124, 3949.822257998397, 3984.6812255019527, 4045.5752146024834, 4049.8987525312104, 1065.898739619735, 1068.6687126990657, 1071.373246650718, 1083.8374243863484, 1087.9238453308192, 910.6266430763355, 914.4284821986258, 929.3283941066453, 934.6495064350869, 979.456401697065, 6373.585901066637, 6383.808485745435, 6441.1626488556185, 6559.409653131241, 6632.336766323426, 925.1488630459993, 933.4026223217146, 944.2068662323682, 971.5967873332629, 981.4503877828296, 3710.276496083238, 3930.8820735377985, 4078.34746440574, 4139.4204811326435, 4285.292926667127, 13484.460621575223, 14026.50907218263, 14029.158250505003, 14050.297485125702, 14084.507042253472, 13565.389557469938, 13813.295222215582, 13859.722805543832, 13962.93018534921, 13981.300934953211, 9894.109530142321, 10149.188064954786, 10255.179585633281, 10260.692179234635, 10282.948585257025, 4834.6374021947795, 6507.772227971998, 6570.737170513126, 6614.570052946543, 6617.2721000688925, 12125.636230913087, 12279.570215042431, 12291.877081229073, 12305.56930507428, 12316.753664926831, 12365.505379784709, 12581.370931453574, 12639.494420223456, 12816.91056260191, 12821.23072615646, 8500.872486912578, 8504.617292221741, 8548.401611887108, 8611.913880861111, 8639.09289524616, 10315.27793054509, 10357.792844143225, 10372.58509659605, 10374.377537347784, 10442.164626829683, 8892.955535222283, 8905.975812781613, 8981.685641002534, 8997.100289970951, 9139.086091390807, 8533.658653653783, 8713.085126061926, 8725.083866194213, 8793.384463087523, 8898.976617689048, 7497.425744544397, 7759.650815713419, 7886.802829929297, 7893.842123157619, 7897.763067107996, 5281.709505977208, 5630.268065151504, 5838.824835254779, 5840.532757379315, 5853.180554722428, 5070.645054846125, 5725.255716756795, 5776.797812076557, 5980.760769569169, 6043.909341359795], "value": 5148.606931052336, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.10918356661665, 370.90028066781986, 380.4958430080143, 385.32375680680417, 389.73303287248245, 4679.251319788835, 4960.20636698128, 5237.554807841338, 5277.340332458442, 5359.758810853511, 4569.520200378962, 4928.704277743338, 5077.238414237866, 5160.741962901859, 5175.715335656538, 4095.2423801596724, 4189.560096189902, 4281.272183728758, 4282.14357128574, 4284.785760711968, 3498.8425520851624, 4084.693647976399, 4247.87256382309, 4291.613754762071, 4316.54676258993, 3511.9824400878088, 4304.504981927072, 4322.243607368714, 4323.740575565461, 4331.0, 3475.4091804393315, 3649.94525082124, 4003.6596889264447, 4272.273713468718, 4295.713778959083, 3583.157957879896, 3671.1005803578028, 3876.127871228979, 3912.5891781363116, 3931.6658084062888, 4067.5729048449784, 4270.530241673413, 5018.949810501921, 5053.570446512051, 5058.293812810129, 4096.426500289962, 4506.211413002711, 4556.612687921531, 4730.955856246799, 4988.800447982077, 4816.903661926777, 5039.899202015976, 5276.076686579833, 5515.931525677871, 5531.529819965308, 4140.399642051916, 4439.933400998988, 4443.933340999855, 4467.0, 4486.8878278042985, 4718.433787945434, 5004.824831130929, 5150.690958542499, 5884.7940322088525, 5900.645961242296, 4313.411952828278, 4646.09510100857, 4822.924628688753, 5029.91856750793, 5050.005247927048, 5125.256837758504, 5152.47055766801, 5159.672011552528, 5176.689398636094, 5193.350831146106, 4671.789769460314, 4765.571098601143, 4798.2802579613335, 4977.938567780789, 5264.078786212397, 5560.443955604488, 5567.944320556742, 5582.720863956786, 5623.971880140573, 5652.547796176296, 1491.8284397294233, 1532.0884073975967, 1634.4361195387573, 1736.3835838277482, 1771.2737777511159, 1192.48723049088, 1220.3105245536422, 1235.7193969452428, 1414.2745850062906, 1445.7108578284385, 4574.428196475441, 4728.13947561727, 4879.706198316122, 5120.692758434506, 5279.102552566073, 5222.503862133091, 5442.755076021588, 5530.866172434641, 5557.861053473695, 5731.589704126039, 4439.200943830077, 4818.349522814376, 4984.675996060245, 5137.717425541631, 5171.8880440705425, 2280.623697089995, 2611.0, 2628.6451329070333, 2775.3894143288444, 2983.5524671299477, 5247.422783493813, 5333.57331413486, 5438.048341540215, 5642.714700546286, 5992.64044157352, 4934.4572097069295, 5276.34045744282, 5674.914876276859, 5854.121881717776, 5945.821625351246, 5319.574434045345, 5744.018313681773, 5749.540036797046, 5903.0, 5986.790462333797, 5469.644473109235, 5535.667859928417, 5624.987502249634, 5724.484796368347, 5726.41674367505, 4955.8017679292425, 5208.348956380452, 5386.468359870854, 5514.711394771285, 5610.438956104357, 4892.165437960764, 4970.110419153315, 5590.888182236251, 5966.134910437875, 6010.248718910137, 4083.8156934488666, 4127.318992366226, 4344.717593356421, 4628.791704373244, 4648.349231107715, 5355.491228333302, 5428.4842939920645, 5572.331320241597, 5699.886002279848, 5767.711614419179, 4874.707517548958, 5122.641415100908, 5278.076336640996, 5523.696196709219, 5585.497305242548, 4426.734395936254, 4713.599344055657, 5082.8232354778265, 5329.760160792851, 5516.668999860021, 5044.369453818273, 5287.149497675783, 5296.761645725875, 5435.048866448276, 5447.727613619224, 4716.103940251342, 5225.335079109605, 5492.47821456961, 5548.112302031655, 5557.638378597269, 4432.335149727567, 4594.816207351669, 4743.264793956942, 5045.4450010498085, 5422.861199148223], "value": 4226.543899712818, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.3266529462302, 721.9169795473522, 756.2626439221756, 811.7661155044333, 828.950262984221, 15336.23318834058, 15690.921545392286, 16028.919855400722, 16032.0, 16051.0, 15339.156346400943, 15403.84596154041, 15527.922360388182, 15586.064836109841, 15590.844091559082, 915.336381123685, 947.3842002698233, 953.5565961827749, 954.6849539651898, 955.126059655418, 897.6005677473527, 940.6002448959199, 941.2846236859967, 952.342883410448, 957.7988622389272, 999.9300048996573, 1001.1240164855751, 1001.9048190421898, 1002.0280328081749, 1004.1715584642662, 982.8280050991084, 993.5975929748463, 994.9950250248775, 995.4077323992213, 996.8903420623726, 733.3656387225066, 736.8968344431784, 740.0712106306551, 741.0181509499935, 741.3328004795673, 15662.373505059784, 15841.207939602973, 16070.357185712557, 16774.83225167757, 16958.491245262663, 14011.579652610337, 14041.22773247471, 14097.340911800939, 14106.294685265799, 14188.290585470788, 11630.244034137753, 11654.76690466186, 11838.585649502225, 11874.643760687193, 12032.458539365749, 3144.4497212987635, 3582.3730847101915, 3648.6372290240524, 3666.981665091684, 3709.536307961505, 91672.0832791677, 92237.53881230619, 102604.0, 120930.39534802358, 123060.38469807594, 10699.732506687395, 10923.907609239172, 11057.778844423066, 11153.38656373891, 11450.969412752893, 21784.34646960594, 21956.890215549138, 22197.445063873532, 22198.667019994715, 22267.888660556593, 6675.3110493026215, 7967.163447838009, 7990.240927111915, 7997.400194985399, 8138.511689298661, 3942.3495123304906, 4324.394584758136, 4345.391645169679, 4369.279068953587, 4389.231884420214, 3127.3276245607303, 3458.8962331130106, 3548.449990251515, 3562.2697347043795, 3567.2152126532123, 647.090837373491, 650.2587050762135, 653.0400311542026, 666.0774826864828, 670.2191946382508, 611.6941529235382, 675.7397453748723, 675.8479342147978, 679.2799632389683, 687.08274453604, 9342.159205671522, 9761.121499065119, 10252.0, 10318.380897146195, 10331.586736530606, 570.3441042800812, 617.6417677746896, 696.9686364113627, 701.0885848397063, 707.9416272672388, 4701.78841952113, 4957.628177886673, 4959.752012399365, 4962.0, 4965.4289756678445, 96629.0, 99565.50217248868, 100096.0, 101640.98359016314, 101681.0, 83674.0, 86872.0, 87767.0, 89005.55497222602, 89313.0, 61174.38825611776, 66360.33639663638, 70893.93659095118, 71644.28355716376, 72250.63874680593, 9265.212456941235, 9520.714378568655, 9554.331196816156, 9588.952055239959, 9680.0, 47424.2886356698, 49001.01997959949, 49054.264186036526, 49113.508864910895, 49674.75162624164, 48708.5129148704, 61722.0, 64204.67897660482, 64403.0, 65043.69912601816, 11025.502544308729, 11138.721531961442, 11177.385243811668, 11190.0, 11609.825852612048, 12545.87454125447, 12720.809187862003, 12852.55016074433, 12966.391130030268, 13104.148230364995, 11686.649400517997, 11723.821584914502, 11921.880781192078, 12020.579279725167, 12323.198992065845, 11672.47473863695, 11900.464479098287, 12072.275663460221, 12277.263364198177, 12290.877091229331, 11716.589919353122, 11784.823227651763, 11976.46105925218, 12274.0, 12297.631071067883, 8368.748937531884, 8370.916290837258, 8562.143785621389, 8747.212750852454, 8781.604827782638, 9207.76980575491, 9728.416295022322, 9944.701658950244, 9956.253281003808, 9958.900410995797], "value": 7376.811782319082, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 133.0, 162.0, 120.0, 119.0, 120.0, 120.0, 138.0, 178.0], "value": 131.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 108.0, 112.0, 145.0, 116.0, 113.0, 114.0, 113.0, 142.0, 113.0], "value": 124.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 198.0, 198.0, 198.0, 198.0, 199.0, 197.0, 196.0, 199.0, 193.0], "value": 197.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 52.0, 71.0, 114.0, 56.0, 81.0, 92.0, 90.0, 99.0, 88.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 88.0, 70.0, 72.0, 91.0, 116.0, 106.0, 81.0, 82.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 88.0, 89.0, 102.0, 89.0, 85.0, 135.0, 88.0, 89.0], "value": 94.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 112.0, 112.0, 92.0, 157.0, 93.0, 155.0, 155.0], "value": 116.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 100.0, 94.0, 149.0, 103.0, 74.0, 145.0, 143.0, 113.0, 138.0], "value": 118.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 69.0, 72.0, 68.0, 68.0, 69.0, 68.0, 68.0], "value": 67.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 63.0, 67.0, 61.0, 58.0, 61.0, 72.0, 74.0, 51.0], "value": 61.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 73.0, 95.0, 115.0, 102.0, 105.0, 95.0, 84.0, 84.0], "value": 92.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 140.0, 169.0, 152.0, 158.0, 147.0, 166.0, 170.0, 199.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 194.0, 186.0, 233.0, 177.0, 155.0, 161.0, 148.0, 146.0, 223.0], "value": 180.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 64.0, 67.0, 66.0, 67.0, 65.0, 63.0, 65.0], "value": 66.0, "unit": "score"}]}]} 14:33:39 INFO - Return code: 0 14:33:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:33:39 ERROR - # TBPL SUCCESS # 14:33:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:33:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:33:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:33:39 INFO - 2017-04-01 14:33:39.389 screenresolution[1943:20499] starting screenresolution argv=screenresolution get 14:33:39 INFO - 2017-04-01 14:33:39.392 screenresolution[1943:20499] Display 0: 1600x1200x32@60 14:33:39 INFO - 2017-04-01 14:33:39.397 screenresolution[1944:20502] starting screenresolution argv=screenresolution list 14:33:39 INFO - Available Modes on Display 0 14:33:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:33:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:33:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:33:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:33:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:33:39 INFO - Intel Iris: 14:33:39 INFO - Chipset Model: Intel Iris 14:33:39 INFO - Type: GPU 14:33:39 INFO - Bus: Built-In 14:33:39 INFO - VRAM (Dynamic, Max): 1536 MB 14:33:39 INFO - Vendor: Intel (0x8086) 14:33:39 INFO - Device ID: 0x0a2e 14:33:39 INFO - Revision ID: 0x0009 14:33:39 INFO - Displays: 14:33:39 INFO - Display: 14:33:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:33:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:33:39 INFO - Display Serial Number: 1600x1200 60 14:33:39 INFO - Main Display: Yes 14:33:39 INFO - Mirror: Off 14:33:39 INFO - Online: Yes 14:33:39 INFO - Rotation: Supported 14:33:39 INFO - Return code: 0 14:33:39 INFO - Running post-action listener: _resource_record_post_action 14:33:39 INFO - [mozharness: 2017-04-01 21:33:39.630872Z] Finished run-tests step (success) 14:33:39 INFO - Running post-run listener: _resource_record_post_run 14:33:39 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 606208; Write bytes: 511545344; Read time: 7; Write time: 1564 14:33:39 INFO - TinderboxPrint: CPU usage
54.5% 14:33:39 INFO - TinderboxPrint: I/O read bytes / time
606,208 / 7 14:33:39 INFO - TinderboxPrint: I/O write bytes / time
511,545,344 / 1,564 14:33:39 INFO - TinderboxPrint: CPU idle
2,184.4 (45.5%) 14:33:39 INFO - TinderboxPrint: CPU system
961.6 (20.0%) 14:33:39 INFO - TinderboxPrint: CPU user
1,651.6 (34.4%) 14:33:39 INFO - TinderboxPrint: Swap in / out
208,330,752 / 0 14:33:39 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205588992; Write bytes: 200460288; Read time: 15920; Write time: 385 14:33:39 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 606208; Write bytes: 300115968; Read time: 7; Write time: 1146 14:33:40 INFO - Running post-run listener: _upload_blobber_files 14:33:40 INFO - Blob upload gear active. 14:33:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:33:40 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 14:33:40 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'] 14:33:40 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 14:33:40 INFO - (blobuploader) - INFO - Open directory for files ... 14:33:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:33:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:33:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:41 INFO - (blobuploader) - INFO - Done attempting. 14:33:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:33:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:33:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:42 INFO - (blobuploader) - INFO - Done attempting. 14:33:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:33:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:33:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:43 INFO - (blobuploader) - INFO - Done attempting. 14:33:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:33:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:33:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:45 INFO - (blobuploader) - INFO - Done attempting. 14:33:45 INFO - (blobuploader) - INFO - Iteration through files over. 14:33:45 INFO - Return code: 0 14:33:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:33:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:33:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f09652ce9bad9bb6dbff65897e7310748bce676b83763c7c6d136921be8a675740eefc09ceb5f312dea26f8c972a1a1addd9a9a619f58df55f8dbe909848346a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5df8e5a64657d0bd2253eefa1987dae3de56665e61da97b8443612bebff8d6eb10cec2a6fda2b5db767395b2cbd2ba34b5801b03b3f717557154e479a887ed14", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a1d2db1cf575211d9c611def731506e85e5e40a56a4c215ed318154900dd91f36df472350fbbce638cd9aa9c02cb18151f04be25e322c270222e355117f9321", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c82d848afb128d340c9a384dec5d5d204f8e31f25f2171497c925255f5374829b870e19319e6cfc84af966024999dd12e078004859b66aa1bcb465e512a65426"} 14:33:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:33:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:33:45 INFO - Contents: 14:33:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f09652ce9bad9bb6dbff65897e7310748bce676b83763c7c6d136921be8a675740eefc09ceb5f312dea26f8c972a1a1addd9a9a619f58df55f8dbe909848346a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5df8e5a64657d0bd2253eefa1987dae3de56665e61da97b8443612bebff8d6eb10cec2a6fda2b5db767395b2cbd2ba34b5801b03b3f717557154e479a887ed14", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a1d2db1cf575211d9c611def731506e85e5e40a56a4c215ed318154900dd91f36df472350fbbce638cd9aa9c02cb18151f04be25e322c270222e355117f9321", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c82d848afb128d340c9a384dec5d5d204f8e31f25f2171497c925255f5374829b870e19319e6cfc84af966024999dd12e078004859b66aa1bcb465e512a65426"} 14:33:45 INFO - Running post-run listener: copy_logs_to_upload_dir 14:33:45 INFO - Copying logs to upload dir... 14:33:45 INFO - mkdir: /builds/slave/test/build/upload/logs 14:33:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.965519 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-01 14:33:45.498091) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-01 14:33:45.501752) ========= 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.TiYGyviFA5/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.n0W05udSwo/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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/f09652ce9bad9bb6dbff65897e7310748bce676b83763c7c6d136921be8a675740eefc09ceb5f312dea26f8c972a1a1addd9a9a619f58df55f8dbe909848346a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5df8e5a64657d0bd2253eefa1987dae3de56665e61da97b8443612bebff8d6eb10cec2a6fda2b5db767395b2cbd2ba34b5801b03b3f717557154e479a887ed14", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a1d2db1cf575211d9c611def731506e85e5e40a56a4c215ed318154900dd91f36df472350fbbce638cd9aa9c02cb18151f04be25e322c270222e355117f9321", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c82d848afb128d340c9a384dec5d5d204f8e31f25f2171497c925255f5374829b870e19319e6cfc84af966024999dd12e078004859b66aa1bcb465e512a65426"} build_url:https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/V-7RCJ9lRFqqUASPz7Xo6A/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/f09652ce9bad9bb6dbff65897e7310748bce676b83763c7c6d136921be8a675740eefc09ceb5f312dea26f8c972a1a1addd9a9a619f58df55f8dbe909848346a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5df8e5a64657d0bd2253eefa1987dae3de56665e61da97b8443612bebff8d6eb10cec2a6fda2b5db767395b2cbd2ba34b5801b03b3f717557154e479a887ed14", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a1d2db1cf575211d9c611def731506e85e5e40a56a4c215ed318154900dd91f36df472350fbbce638cd9aa9c02cb18151f04be25e322c270222e355117f9321", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c82d848afb128d340c9a384dec5d5d204f8e31f25f2171497c925255f5374829b870e19319e6cfc84af966024999dd12e078004859b66aa1bcb465e512a65426"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-01 14:33:45.536330) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 14:33:45.536643) ========= 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.TiYGyviFA5/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.n0W05udSwo/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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.004966 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 14:33:45.585294) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-01 14:33:45.585660) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-01 14:33:45.585989) ========= ========= Total master_lag: 0.19 =========