builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0233 starttime: 1490830268.32 results: success (0) buildid: 20170329152123 builduid: ae6f9242b9684ab19901695c8da0ce78 revision: fc2eda92be4091d5ea0828f51fe4aaee41ed8319 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 16:31:08.316630) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 16:31:08.317177) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-29 16:31:08.317452) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-29 16:31:08.343165) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:31:08.343579) ========= 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.TjUNK30j7g/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.DnKeNVZ9qC/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-29 16:31:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.89M=0.001s 2017-03-29 16:31:08 (7.89 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.110560 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:31:08.480195) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:31:08.480854) ========= 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.TjUNK30j7g/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.DnKeNVZ9qC/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.021488 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:31:08.530801) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-29 16:31:08.531225) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fc2eda92be4091d5ea0828f51fe4aaee41ed8319 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev fc2eda92be4091d5ea0828f51fe4aaee41ed8319 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TjUNK30j7g/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.DnKeNVZ9qC/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-29 16:31:08,621 truncating revision to first 12 chars 2017-03-29 16:31:08,621 Setting DEBUG logging. 2017-03-29 16:31:08,622 attempt 1/10 2017-03-29 16:31:08,622 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fc2eda92be40?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-29 16:31:09,468 unpacking tar archive at: mozilla-central-fc2eda92be40/testing/mozharness/ program finished with exit code 0 elapsedTime=1.116854 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-29 16:31:09.672066) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 16:31:09.672384) ========= script_repo_revision: fc2eda92be4091d5ea0828f51fe4aaee41ed8319 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 16:31:09.672853) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-29 16:31:09.673289) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-29 16:31:09.693309) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-03-29 16:31:09.693980) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TjUNK30j7g/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.DnKeNVZ9qC/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 16:31:09 INFO - MultiFileLogger online at 20170329 16:31:09 in /builds/slave/test 16:31:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:31:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:31:09 INFO - {'append_to_log': False, 16:31:09 INFO - 'base_work_dir': '/builds/slave/test', 16:31:09 INFO - 'blob_upload_branch': 'Firefox', 16:31:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:31:09 INFO - 'branch': 'Firefox', 16:31:09 INFO - 'buildbot_json_path': 'buildprops.json', 16:31:09 INFO - 'config_files': ('talos/mac_config.py',), 16:31:09 INFO - 'default_actions': ('clobber', 16:31:09 INFO - 'read-buildbot-config', 16:31:09 INFO - 'download-and-extract', 16:31:09 INFO - 'populate-webroot', 16:31:09 INFO - 'create-virtualenv', 16:31:09 INFO - 'install', 16:31:09 INFO - 'run-tests'), 16:31:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:31:09 INFO - 'download_minidump_stackwalk': True, 16:31:09 INFO - 'download_symbols': 'ondemand', 16:31:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:31:09 INFO - 'tooltool.py': '/tools/tooltool.py', 16:31:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:31:09 INFO - '/tools/misc-python/virtualenv.py')}, 16:31:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:31:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:31:09 INFO - 'gecko_profile': False, 16:31:09 INFO - 'gecko_profile_interval': 0, 16:31:09 INFO - 'installer_path': 'installer.exe', 16:31:09 INFO - 'log_level': 'info', 16:31:09 INFO - 'log_name': 'talos', 16:31:09 INFO - 'log_to_console': True, 16:31:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:31:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:31:09 INFO - 'opt_config_files': (), 16:31:09 INFO - 'pip_index': False, 16:31:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:31:09 INFO - 'cmd': ('bash', 16:31:09 INFO - '-c', 16:31:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:31:09 INFO - 'enabled': True, 16:31:09 INFO - 'halt_on_failure': False, 16:31:09 INFO - 'name': 'check_screen_resolution'},), 16:31:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:31:09 INFO - 'cmd': ('bash', 16:31:09 INFO - '-c', 16:31:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:31:09 INFO - 'enabled': True, 16:31:09 INFO - 'halt_on_failure': False, 16:31:09 INFO - 'name': 'check_screen_resolution'},), 16:31:09 INFO - 'run_cmd_checks_enabled': True, 16:31:09 INFO - 'suite': 'dromaeojs', 16:31:09 INFO - 'system_bits': '32', 16:31:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:31:09 INFO - 'title': 't-yosemite-r7-0233', 16:31:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:31:09 INFO - 'use_talos_json': True, 16:31:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:31:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:31:09 INFO - 'work_dir': 'build'} 16:31:09 INFO - [mozharness: 2017-03-29 23:31:09.902132Z] Running clobber step. 16:31:09 INFO - Running pre-action listener: _resource_record_pre_action 16:31:09 INFO - Running main action method: clobber 16:31:09 INFO - rmtree: /builds/slave/test/build 16:31:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:31:12 INFO - Running post-action listener: _resource_record_post_action 16:31:12 INFO - [mozharness: 2017-03-29 23:31:12.065990Z] Finished clobber step (success) 16:31:12 INFO - [mozharness: 2017-03-29 23:31:12.066114Z] Running read-buildbot-config step. 16:31:12 INFO - Running pre-action listener: _resource_record_pre_action 16:31:12 INFO - Running main action method: read_buildbot_config 16:31:12 INFO - Using buildbot properties: 16:31:12 INFO - { 16:31:12 INFO - "project": "", 16:31:12 INFO - "product": "firefox", 16:31:12 INFO - "script_repo_revision": "production", 16:31:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:31:12 INFO - "repository": "", 16:31:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:31:12 INFO - "buildid": "20170329152123", 16:31:12 INFO - "pgo_build": "False", 16:31:12 INFO - "basedir": "/builds/slave/test", 16:31:12 INFO - "buildnumber": 2, 16:31:12 INFO - "slavename": "t-yosemite-r7-0233", 16:31:12 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:31:12 INFO - "platform": "yosemite_r7", 16:31:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:31:12 INFO - "branch": "mozilla-central", 16:31:12 INFO - "repo_path": "mozilla-central", 16:31:12 INFO - "revision": "fc2eda92be4091d5ea0828f51fe4aaee41ed8319", 16:31:12 INFO - "stage_platform": "macosx64", 16:31:12 INFO - "builduid": "ae6f9242b9684ab19901695c8da0ce78", 16:31:12 INFO - "slavebuilddir": "test" 16:31:12 INFO - } 16:31:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:31:12 INFO - Running post-action listener: _resource_record_post_action 16:31:12 INFO - [mozharness: 2017-03-29 23:31:12.069626Z] Finished read-buildbot-config step (success) 16:31:12 INFO - [mozharness: 2017-03-29 23:31:12.069745Z] Running download-and-extract step. 16:31:12 INFO - Running pre-action listener: _resource_record_pre_action 16:31:12 INFO - Running main action method: download_and_extract 16:31:12 INFO - mkdir: /builds/slave/test/build/tests 16:31:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:12 INFO - trying https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:31:12 INFO - Downloading https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:31:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 16:31:13 INFO - Downloaded 1325 bytes. 16:31:13 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:31:13 INFO - Using the following test package requirements: 16:31:13 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:31:13 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:31:13 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:31:13 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:31:13 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:31:13 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:31:13 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:31:13 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:31:13 INFO - u'jsshell-mac.zip'], 16:31:13 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:31:13 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:31:13 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:31:13 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:31:13 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:31:13 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:31:13 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:31:13 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:31:13 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:31:13 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:31:13 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:31:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:31:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:31:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:31:13 INFO - Fetch https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:31:14 INFO - Expected file size: 52504514 16:31:14 INFO - Obtained file size: 52504514 16:31:30 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:31:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:31:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:31:30 INFO - Fetch https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:31:31 INFO - Expected file size: 52504514 16:31:31 INFO - Obtained file size: 52504514 16:31:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:31:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:31:47 INFO - Fetch https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:31:48 INFO - Expected file size: 14404171 16:31:48 INFO - Obtained file size: 14404171 16:31:49 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')]} 16:31:49 INFO - trying https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:31:49 INFO - Downloading https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:31:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 16:31:51 INFO - Downloaded 75552434 bytes. 16:31:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:31:51 INFO - mkdir: /builds/slave/test/properties 16:31:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:31:51 INFO - Writing to file /builds/slave/test/properties/build_url 16:31:51 INFO - Contents: 16:31:51 INFO - build_url:https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:31:51 INFO - Running post-action listener: _resource_record_post_action 16:31:51 INFO - Running post-action listener: set_extra_try_arguments 16:31:51 INFO - [mozharness: 2017-03-29 23:31:51.950834Z] Finished download-and-extract step (success) 16:31:51 INFO - [mozharness: 2017-03-29 23:31:51.950972Z] Running populate-webroot step. 16:31:51 INFO - Running pre-action listener: _resource_record_pre_action 16:31:51 INFO - Running main action method: populate_webroot 16:31:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:31:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:31:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:31:51 INFO - '--tppagecycles', 16:31:51 INFO - '7'], 16:31:51 INFO - 'tests': ['tsvgm']}}, 16:31:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:31:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:31:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:31:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:31:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:31:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:31:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:31:51 INFO - 'talos_options': ['--disable-e10s'], 16:31:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:31:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:31:51 INFO - 'talos_options': ['--disable-e10s'], 16:31:51 INFO - 'tests': ['damp', 'tps']}, 16:31:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:31:51 INFO - 'tests': ['damp', 'tps']}, 16:31:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:31:51 INFO - 'tests': ['dromaeo_dom']}, 16:31:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:31:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:31:51 INFO - 'tests': ['basic_compositor_video']}, 16:31:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:31:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:31:51 INFO - 'tests': ['a11yr', 16:31:51 INFO - 'ts_paint', 16:31:51 INFO - 'tpaint', 16:31:51 INFO - 'sessionrestore', 16:31:51 INFO - 'sessionrestore_no_auto_restore', 16:31:51 INFO - 'tabpaint']}, 16:31:51 INFO - 'other-e10s': {'tests': ['a11yr', 16:31:51 INFO - 'ts_paint', 16:31:51 INFO - 'tpaint', 16:31:51 INFO - 'sessionrestore', 16:31:51 INFO - 'sessionrestore_no_auto_restore', 16:31:51 INFO - 'tabpaint']}, 16:31:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:31:51 INFO - 'ts_paint', 16:31:51 INFO - 'tpaint', 16:31:51 INFO - 'sessionrestore', 16:31:51 INFO - 'sessionrestore_no_auto_restore', 16:31:51 INFO - 'tabpaint']}, 16:31:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:31:51 INFO - 'ts_paint', 16:31:51 INFO - 'tpaint', 16:31:51 INFO - 'sessionrestore', 16:31:51 INFO - 'sessionrestore_no_auto_restore', 16:31:51 INFO - 'tabpaint']}, 16:31:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:31:51 INFO - 'tests': ['a11yr', 16:31:51 INFO - 'ts_paint', 16:31:51 INFO - 'tpaint', 16:31:51 INFO - 'sessionrestore', 16:31:51 INFO - 'sessionrestore_no_auto_restore', 16:31:51 INFO - 'tabpaint']}, 16:31:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:31:51 INFO - 'tests': ['a11yr', 16:31:51 INFO - 'ts_paint', 16:31:51 INFO - 'tpaint', 16:31:51 INFO - 'sessionrestore', 16:31:51 INFO - 'sessionrestore_no_auto_restore', 16:31:51 INFO - 'tabpaint']}, 16:31:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:31:51 INFO - 'tests': ['tsvgx', 16:31:51 INFO - 'tsvgr_opacity', 16:31:51 INFO - 'tart', 16:31:51 INFO - 'tscrollx', 16:31:51 INFO - 'cart', 16:31:51 INFO - 'tsvg_static']}, 16:31:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:31:51 INFO - 'tsvgr_opacity', 16:31:51 INFO - 'tart', 16:31:51 INFO - 'tscrollx', 16:31:51 INFO - 'cart', 16:31:51 INFO - 'tsvg_static']}, 16:31:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:31:51 INFO - 'talos_options': ['--disable-e10s'], 16:31:51 INFO - 'tests': ['tp5o']}, 16:31:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:31:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:31:51 INFO - 'talos_options': ['--disable-e10s', 16:31:51 INFO - '--xperf_path', 16:31:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:51 INFO - 'tests': ['tp5n']}, 16:31:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:31:51 INFO - 'talos_options': ['--xperf_path', 16:31:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:51 INFO - 'tests': ['tp5n']}}, 16:31:51 INFO - 'talos.zip': {'path': '', 16:31:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:31:51 INFO - Running post-action listener: _resource_record_post_action 16:31:51 INFO - [mozharness: 2017-03-29 23:31:51.969037Z] Finished populate-webroot step (success) 16:31:51 INFO - [mozharness: 2017-03-29 23:31:51.969174Z] Running create-virtualenv step. 16:31:51 INFO - Running pre-action listener: _resource_record_pre_action 16:31:51 INFO - Running main action method: create_virtualenv 16:31:51 INFO - Creating virtualenv /builds/slave/test/build/venv 16:31:51 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 16:31:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:31:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:31:52 INFO - Using real prefix '/tools/python27' 16:31:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:31:52 INFO - Installing distribute.............................................................................................................................................................................................done. 16:31:55 INFO - Installing pip.................done. 16:31:55 INFO - Return code: 0 16:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:31:55 INFO - Reading from file tmpfile_stdout 16:31:55 INFO - Output received: 16:31:55 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) 16:31:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:55 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 0x109254b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109257768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8ea483b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109032d30>, '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 0x108f59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109027be8>, '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.TjUNK30j7g/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.DnKeNVZ9qC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 16:31:55 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 16:31:55 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 16:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjUNK30j7g/Render', 16:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:55 INFO - 'HOME': '/Users/cltbld', 16:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:55 INFO - 'LOGNAME': 'cltbld', 16:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:55 INFO - 'MOZ_NO_REMOTE': '1', 16:31:55 INFO - 'NO_EM_RESTART': '1', 16:31:55 INFO - 'PAGER': '/bin/cat', 16:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:55 INFO - 'PWD': '/builds/slave/test', 16:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:55 INFO - 'SHELL': '/bin/bash', 16:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DnKeNVZ9qC/Listeners', 16:31:55 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 16:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:55 INFO - 'USER': 'cltbld', 16:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:55 INFO - 'XPC_FLAGS': '0x0', 16:31:55 INFO - 'XPC_SERVICE_NAME': '0', 16:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:56 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 16:31:56 INFO - Cleaning up... 16:31:56 INFO - Return code: 0 16:31:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:31:56 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')]} 16:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:56 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')]} 16:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:56 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 0x109254b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109257768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8ea483b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109032d30>, '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 0x108f59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109027be8>, '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.TjUNK30j7g/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.DnKeNVZ9qC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 16:31:56 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 16:31:56 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 16:31:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjUNK30j7g/Render', 16:31:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:56 INFO - 'HOME': '/Users/cltbld', 16:31:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:56 INFO - 'LOGNAME': 'cltbld', 16:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:56 INFO - 'MOZ_NO_REMOTE': '1', 16:31:56 INFO - 'NO_EM_RESTART': '1', 16:31:56 INFO - 'PAGER': '/bin/cat', 16:31:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:56 INFO - 'PWD': '/builds/slave/test', 16:31:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:56 INFO - 'SHELL': '/bin/bash', 16:31:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DnKeNVZ9qC/Listeners', 16:31:56 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 16:31:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:56 INFO - 'USER': 'cltbld', 16:31:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:56 INFO - 'XPC_FLAGS': '0x0', 16:31:56 INFO - 'XPC_SERVICE_NAME': '0', 16:31:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:56 INFO - Downloading/unpacking psutil>=3.1.1 16:31:56 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 16:31:56 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 16:31:56 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 16:31:56 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 16:31:56 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 16:31:56 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 16:31:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:31:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:31:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:31:59 INFO - Installing collected packages: psutil 16:31:59 INFO - Running setup.py install for psutil 16:31:59 INFO - building 'psutil._psutil_osx' extension 16:31:59 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 16:32:00 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 16:32:00 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 16:32:00 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 16:32:00 INFO - building 'psutil._psutil_posix' extension 16:32:00 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 16:32:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:32:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:32:00 INFO - ^ 16:32:00 INFO - 1 warning generated. 16:32:00 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 16:32:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:32:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:32:00 INFO - Successfully installed psutil 16:32:00 INFO - Cleaning up... 16:32:00 INFO - Return code: 0 16:32:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:32: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')]} 16:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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')]} 16:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109254b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109257768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8ea483b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109032d30>, '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 0x108f59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109027be8>, '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.TjUNK30j7g/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.DnKeNVZ9qC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 16:32:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:32:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjUNK30j7g/Render', 16:32:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:00 INFO - 'HOME': '/Users/cltbld', 16:32:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:00 INFO - 'LOGNAME': 'cltbld', 16:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:00 INFO - 'MOZ_NO_REMOTE': '1', 16:32:00 INFO - 'NO_EM_RESTART': '1', 16:32:00 INFO - 'PAGER': '/bin/cat', 16:32:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:00 INFO - 'PWD': '/builds/slave/test', 16:32:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:00 INFO - 'SHELL': '/bin/bash', 16:32:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DnKeNVZ9qC/Listeners', 16:32:00 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 16:32:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:00 INFO - 'USER': 'cltbld', 16:32:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:00 INFO - 'XPC_FLAGS': '0x0', 16:32:00 INFO - 'XPC_SERVICE_NAME': '0', 16:32:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:32:03 INFO - Installing collected packages: mozsystemmonitor 16:32:03 INFO - Running setup.py install for mozsystemmonitor 16:32:03 INFO - Successfully installed mozsystemmonitor 16:32:03 INFO - Cleaning up... 16:32:04 INFO - Return code: 0 16:32:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109254b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109257768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8ea483b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109032d30>, '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 0x108f59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109027be8>, '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.TjUNK30j7g/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.DnKeNVZ9qC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 16:32:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjUNK30j7g/Render', 16:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:04 INFO - 'HOME': '/Users/cltbld', 16:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:04 INFO - 'LOGNAME': 'cltbld', 16:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:04 INFO - 'MOZ_NO_REMOTE': '1', 16:32:04 INFO - 'NO_EM_RESTART': '1', 16:32:04 INFO - 'PAGER': '/bin/cat', 16:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:04 INFO - 'PWD': '/builds/slave/test', 16:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:04 INFO - 'SHELL': '/bin/bash', 16:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DnKeNVZ9qC/Listeners', 16:32:04 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 16:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:04 INFO - 'USER': 'cltbld', 16:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:04 INFO - 'XPC_FLAGS': '0x0', 16:32:04 INFO - 'XPC_SERVICE_NAME': '0', 16:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:04 INFO - Downloading/unpacking jsonschema==2.5.1 16:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:32:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:08 INFO - Downloading functools32-3.2.3-2.tar.gz 16:32:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:32:08 INFO - warning: no files found matching '*.txt' 16:32:08 INFO - no previously-included directories found matching 'build' 16:32:08 INFO - no previously-included directories found matching 'dist' 16:32:08 INFO - no previously-included directories found matching '.git*' 16:32:08 INFO - Installing collected packages: jsonschema, functools32 16:32:08 INFO - Running setup.py install for functools32 16:32:08 INFO - warning: no files found matching '*.txt' 16:32:08 INFO - no previously-included directories found matching 'build' 16:32:08 INFO - no previously-included directories found matching 'dist' 16:32:08 INFO - no previously-included directories found matching '.git*' 16:32:08 INFO - Successfully installed jsonschema functools32 16:32:08 INFO - Cleaning up... 16:32:08 INFO - Return code: 0 16:32:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:32:08 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')]} 16:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109254b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109257768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8ea483b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109032d30>, '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 0x108f59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109027be8>, '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.TjUNK30j7g/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.DnKeNVZ9qC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 16:32:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:32:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjUNK30j7g/Render', 16:32:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:09 INFO - 'HOME': '/Users/cltbld', 16:32:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:09 INFO - 'LOGNAME': 'cltbld', 16:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:09 INFO - 'MOZ_NO_REMOTE': '1', 16:32:09 INFO - 'NO_EM_RESTART': '1', 16:32:09 INFO - 'PAGER': '/bin/cat', 16:32:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:09 INFO - 'PWD': '/builds/slave/test', 16:32:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:09 INFO - 'SHELL': '/bin/bash', 16:32:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DnKeNVZ9qC/Listeners', 16:32:09 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 16:32:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:09 INFO - 'USER': 'cltbld', 16:32:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:09 INFO - 'XPC_FLAGS': '0x0', 16:32:09 INFO - 'XPC_SERVICE_NAME': '0', 16:32:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:32:09 INFO - Cleaning up... 16:32:09 INFO - Return code: 0 16:32:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109254b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109257768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8ea483b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109032d30>, '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 0x108f59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109027be8>, '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.TjUNK30j7g/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.DnKeNVZ9qC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 16:32:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:32:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjUNK30j7g/Render', 16:32:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:09 INFO - 'HOME': '/Users/cltbld', 16:32:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:09 INFO - 'LOGNAME': 'cltbld', 16:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:09 INFO - 'MOZ_NO_REMOTE': '1', 16:32:09 INFO - 'NO_EM_RESTART': '1', 16:32:09 INFO - 'PAGER': '/bin/cat', 16:32:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:09 INFO - 'PWD': '/builds/slave/test', 16:32:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:09 INFO - 'SHELL': '/bin/bash', 16:32:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DnKeNVZ9qC/Listeners', 16:32:09 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 16:32:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:09 INFO - 'USER': 'cltbld', 16:32:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:09 INFO - 'XPC_FLAGS': '0x0', 16:32:09 INFO - 'XPC_SERVICE_NAME': '0', 16:32:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:09 INFO - Downloading/unpacking blobuploader==1.2.4 16:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:12 INFO - Downloading blobuploader-1.2.4.tar.gz 16:32:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:32:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:32:12 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 16:32:12 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 16:32:12 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 16:32:12 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 16:32:12 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 16:32:12 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 16:32:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:32:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:13 INFO - Downloading docopt-0.6.1.tar.gz 16:32:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:32:13 INFO - Installing collected packages: blobuploader, requests, docopt 16:32:13 INFO - Running setup.py install for blobuploader 16:32:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:32:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:32:13 INFO - Running setup.py install for requests 16:32:14 INFO - Running setup.py install for docopt 16:32:14 INFO - Successfully installed blobuploader requests docopt 16:32:14 INFO - Cleaning up... 16:32:14 INFO - Return code: 0 16:32:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:14 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 0x109254b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109257768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8ea483b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109032d30>, '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 0x108f59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109027be8>, '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.TjUNK30j7g/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.DnKeNVZ9qC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 16:32:14 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 16:32:14 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 16:32:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjUNK30j7g/Render', 16:32:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:14 INFO - 'HOME': '/Users/cltbld', 16:32:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:14 INFO - 'LOGNAME': 'cltbld', 16:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:14 INFO - 'MOZ_NO_REMOTE': '1', 16:32:14 INFO - 'NO_EM_RESTART': '1', 16:32:14 INFO - 'PAGER': '/bin/cat', 16:32:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:14 INFO - 'PWD': '/builds/slave/test', 16:32:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:14 INFO - 'SHELL': '/bin/bash', 16:32:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DnKeNVZ9qC/Listeners', 16:32:14 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 16:32:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:14 INFO - 'USER': 'cltbld', 16:32:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:14 INFO - 'XPC_FLAGS': '0x0', 16:32:14 INFO - 'XPC_SERVICE_NAME': '0', 16:32:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:32:14 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-I0X2KO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:32:14 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-UyHuib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:32:14 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-bMEpGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:32:15 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-KjiQEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:32:15 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-EXx2zz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:32:15 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-wz5J7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:32:15 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-1LDvve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:32:15 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-fR6NrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:32:15 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-M9TSsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:32:15 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-BUd5mi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:32:15 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-qsFoqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:32:15 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-PecqiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:32:15 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-3GyVEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:32:16 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-GTn7O6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:16 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-M8lP_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:32:16 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-tU37p1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:16 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-CNTtq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:32:16 INFO - Running setup.py install for manifestparser 16:32:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:32:16 INFO - Running setup.py install for mozcrash 16:32:16 INFO - Running setup.py install for mozdebug 16:32:16 INFO - Running setup.py install for mozdevice 16:32:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Running setup.py install for mozfile 16:32:17 INFO - Running setup.py install for mozhttpd 16:32:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Running setup.py install for mozinfo 16:32:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Running setup.py install for mozInstall 16:32:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Running setup.py install for mozleak 16:32:17 INFO - Running setup.py install for mozlog 16:32:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:32:17 INFO - Running setup.py install for moznetwork 16:32:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:32:18 INFO - Running setup.py install for mozprocess 16:32:18 INFO - Running setup.py install for mozprofile 16:32:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:32:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:32:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:32:18 INFO - Running setup.py install for mozrunner 16:32:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:32:18 INFO - Running setup.py install for mozscreenshot 16:32:18 INFO - Running setup.py install for moztest 16:32:18 INFO - Running setup.py install for mozversion 16:32:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:32:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:32:19 INFO - Cleaning up... 16:32:19 INFO - Return code: 0 16:32:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:19 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 0x109254b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109257768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8ea483b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109032d30>, '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 0x108f59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109027be8>, '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.TjUNK30j7g/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.DnKeNVZ9qC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 16:32:19 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 16:32:19 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 16:32:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjUNK30j7g/Render', 16:32:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:19 INFO - 'HOME': '/Users/cltbld', 16:32:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:19 INFO - 'LOGNAME': 'cltbld', 16:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:19 INFO - 'MOZ_NO_REMOTE': '1', 16:32:19 INFO - 'NO_EM_RESTART': '1', 16:32:19 INFO - 'PAGER': '/bin/cat', 16:32:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:19 INFO - 'PWD': '/builds/slave/test', 16:32:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:19 INFO - 'SHELL': '/bin/bash', 16:32:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DnKeNVZ9qC/Listeners', 16:32:19 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 16:32:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:19 INFO - 'USER': 'cltbld', 16:32:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:19 INFO - 'XPC_FLAGS': '0x0', 16:32:19 INFO - 'XPC_SERVICE_NAME': '0', 16:32:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:32:19 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-bLhezn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:32:19 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-9Jh9oc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:32:19 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-4d1ymy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:32:19 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-oxT2eV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:32:19 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-qtTJAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:32:19 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-sO6VR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:32:19 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)) 16:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:32:19 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-IB5t7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:32:20 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)) 16:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:32:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-E4Ld62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:32:20 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)) 16:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:32:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-CBTYLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:32:20 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)) 16:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:32:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-hELDG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:32:20 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)) 16:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:32:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-06fd43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:32:20 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)) 16:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:32:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-LgUtru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:32:20 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)) 16:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:32:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-lOJmRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:32:20 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)) 16:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:32:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-kVGiQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:32:20 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)) 16:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-pdi4uJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:20 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)) 16:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:32:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-8yzUN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:20 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)) 16:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:20 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-oZpvxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:21 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)) 16:32:21 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)) 16:32:21 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)) 16:32:21 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)) 16:32:21 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)) 16:32:21 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)) 16:32:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32:23 INFO - Downloading blessings-1.6.tar.gz 16:32:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:32:23 INFO - Installing collected packages: blessings 16:32:23 INFO - Running setup.py install for blessings 16:32:23 INFO - Successfully installed blessings 16:32:23 INFO - Cleaning up... 16:32:24 INFO - Return code: 0 16:32:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:32:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:32:24 INFO - Reading from file tmpfile_stdout 16:32:24 INFO - Current package versions: 16:32:24 INFO - blessings == 1.6 16:32:24 INFO - blobuploader == 1.2.4 16:32:24 INFO - docopt == 0.6.1 16:32:24 INFO - functools32 == 3.2.3-2 16:32:24 INFO - jsonschema == 2.5.1 16:32:24 INFO - manifestparser == 1.1 16:32:24 INFO - mozInstall == 1.12 16:32:24 INFO - mozcrash == 1.0 16:32:24 INFO - mozdebug == 0.1 16:32:24 INFO - mozdevice == 0.49 16:32:24 INFO - mozfile == 1.2 16:32:24 INFO - mozhttpd == 0.7 16:32:24 INFO - mozinfo == 0.9 16:32:24 INFO - mozleak == 0.1 16:32:24 INFO - mozlog == 3.4 16:32:24 INFO - moznetwork == 0.27 16:32:24 INFO - mozprocess == 0.25 16:32:24 INFO - mozprofile == 0.28 16:32:24 INFO - mozrunner == 6.13 16:32:24 INFO - mozscreenshot == 0.1 16:32:24 INFO - mozsystemmonitor == 0.3 16:32:24 INFO - moztest == 0.8 16:32:24 INFO - mozversion == 1.4 16:32:24 INFO - psutil == 3.1.1 16:32:24 INFO - requests == 1.2.3 16:32:24 INFO - wsgiref == 0.1.2 16:32:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:24 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 0x109254b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109257768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8ea483b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109032d30>, '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 0x108f59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109027be8>, '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.TjUNK30j7g/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.DnKeNVZ9qC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 16:32:24 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 16:32:24 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 16:32:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjUNK30j7g/Render', 16:32:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:24 INFO - 'HOME': '/Users/cltbld', 16:32:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:24 INFO - 'LOGNAME': 'cltbld', 16:32:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:24 INFO - 'MOZ_NO_REMOTE': '1', 16:32:24 INFO - 'NO_EM_RESTART': '1', 16:32:24 INFO - 'PAGER': '/bin/cat', 16:32:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:24 INFO - 'PWD': '/builds/slave/test', 16:32:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:24 INFO - 'SHELL': '/bin/bash', 16:32:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DnKeNVZ9qC/Listeners', 16:32:24 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 16:32:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:24 INFO - 'USER': 'cltbld', 16:32:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:24 INFO - 'XPC_FLAGS': '0x0', 16:32:24 INFO - 'XPC_SERVICE_NAME': '0', 16:32:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:24 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)) 16:32:24 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)) 16:32:24 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)) 16:32:24 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)) 16:32:24 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)) 16:32:24 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)) 16:32:24 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)) 16:32:24 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)) 16:32:24 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)) 16:32:24 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)) 16:32:24 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)) 16:32:24 INFO - Cleaning up... 16:32:24 INFO - Return code: 0 16:32:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:24 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 0x109254b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109257768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8ea483b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109032d30>, '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 0x108f59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109027be8>, '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.TjUNK30j7g/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.DnKeNVZ9qC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 16:32:24 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 16:32:24 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 16:32:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjUNK30j7g/Render', 16:32:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:24 INFO - 'HOME': '/Users/cltbld', 16:32:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:24 INFO - 'LOGNAME': 'cltbld', 16:32:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:24 INFO - 'MOZ_NO_REMOTE': '1', 16:32:24 INFO - 'NO_EM_RESTART': '1', 16:32:24 INFO - 'PAGER': '/bin/cat', 16:32:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:24 INFO - 'PWD': '/builds/slave/test', 16:32:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:24 INFO - 'SHELL': '/bin/bash', 16:32:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DnKeNVZ9qC/Listeners', 16:32:24 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 16:32:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:24 INFO - 'USER': 'cltbld', 16:32:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:24 INFO - 'XPC_FLAGS': '0x0', 16:32:24 INFO - 'XPC_SERVICE_NAME': '0', 16:32:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:32:24 INFO - Cleaning up... 16:32:24 INFO - Return code: 0 16:32:24 INFO - Running post-action listener: _resource_record_post_action 16:32:24 INFO - Running post-action listener: _start_resource_monitoring 16:32:24 INFO - Starting resource monitoring. 16:32:24 INFO - [mozharness: 2017-03-29 23:32:24.889073Z] Finished create-virtualenv step (success) 16:32:24 INFO - [mozharness: 2017-03-29 23:32:24.890074Z] Running install step. 16:32:24 INFO - Running pre-action listener: _resource_record_pre_action 16:32:24 INFO - Running main action method: install 16:32:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:32:25 INFO - Reading from file tmpfile_stdout 16:32:25 INFO - Detecting whether we're running mozinstall >=1.0... 16:32:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:32:25 INFO - Reading from file tmpfile_stdout 16:32:25 INFO - Output received: 16:32:25 INFO - Usage: mozinstall [options] installer 16:32:25 INFO - Options: 16:32:25 INFO - -h, --help show this help message and exit 16:32:25 INFO - -d DEST, --destination=DEST 16:32:25 INFO - Directory to install application into. [default: 16:32:25 INFO - "/builds/slave/test"] 16:32:25 INFO - --app=APP Application being installed. [default: firefox] 16:32:25 INFO - mkdir: /builds/slave/test/build/application 16:32:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:32:45 INFO - Reading from file tmpfile_stdout 16:32:45 INFO - Output received: 16:32:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:32:45 INFO - Running post-action listener: _resource_record_post_action 16:32:45 INFO - [mozharness: 2017-03-29 23:32:45.620863Z] Finished install step (success) 16:32:45 INFO - [mozharness: 2017-03-29 23:32:45.621020Z] Running run-tests step. 16:32:45 INFO - Running pre-action listener: _resource_record_pre_action 16:32:45 INFO - Running main action method: run_tests 16:32:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:32:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:32:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:32:45 INFO - 2017-03-29 16:32:45.636 screenresolution[1954:18014] starting screenresolution argv=screenresolution get 16:32:45 INFO - 2017-03-29 16:32:45.638 screenresolution[1954:18014] Display 0: 1600x1200x32@60 16:32:45 INFO - 2017-03-29 16:32:45.644 screenresolution[1955:18017] starting screenresolution argv=screenresolution list 16:32:45 INFO - Available Modes on Display 0 16:32:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:32:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:32:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:32:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:32:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:32:45 INFO - Intel Iris: 16:32:45 INFO - Chipset Model: Intel Iris 16:32:45 INFO - Type: GPU 16:32:45 INFO - Bus: Built-In 16:32:45 INFO - VRAM (Dynamic, Max): 1536 MB 16:32:45 INFO - Vendor: Intel (0x8086) 16:32:45 INFO - Device ID: 0x0a2e 16:32:45 INFO - Revision ID: 0x0009 16:32:45 INFO - Displays: 16:32:45 INFO - Display: 16:32:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:32:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:32:45 INFO - Display Serial Number: 1600x1200 60 16:32:45 INFO - Main Display: Yes 16:32:45 INFO - Mirror: Off 16:32:45 INFO - Online: Yes 16:32:45 INFO - Rotation: Supported 16:32:45 INFO - Return code: 0 16:32:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:32:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:32:45 INFO - Python 2.7.3 16:32:45 INFO - Return code: 0 16:32:45 INFO - grabbing minidump binary from tooltool 16:32:45 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')]} 16:32:45 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 0x109032d30>, '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 0x108f59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109027be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:32:45 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 16:32:45 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 16:32:45 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 16:32:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:32:46 INFO - Return code: 0 16:32:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:32:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:32:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:32:46 INFO - ENV: RUST_BACKTRACE is now 1 16:32:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:32:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:32:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0233 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TjUNK30j7g/Render', 16:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:46 INFO - 'HOME': '/Users/cltbld', 16:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:46 INFO - 'LOGNAME': 'cltbld', 16:32:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:32:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:46 INFO - 'MOZ_NO_REMOTE': '1', 16:32:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:32:46 INFO - 'NO_EM_RESTART': '1', 16:32:46 INFO - 'PAGER': '/bin/cat', 16:32:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:46 INFO - 'PWD': '/builds/slave/test', 16:32:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:46 INFO - 'RUST_BACKTRACE': '1', 16:32:46 INFO - 'SHELL': '/bin/bash', 16:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DnKeNVZ9qC/Listeners', 16:32:46 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 16:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:46 INFO - 'USER': 'cltbld', 16:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:46 INFO - 'XPC_FLAGS': '0x0', 16:32:46 INFO - 'XPC_SERVICE_NAME': '0', 16:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:32:46 INFO - mozversion application_buildid: 20170329152123 16:32:46 INFO - mozversion application_changeset: fc2eda92be4091d5ea0828f51fe4aaee41ed8319 16:32:46 INFO - mozversion application_display_name: Nightly 16:32:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:32:46 INFO - mozversion application_name: Firefox 16:32:46 INFO - mozversion application_remotingname: firefox 16:32:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:32:46 INFO - mozversion application_vendor: Mozilla 16:32:46 INFO - mozversion application_version: 55.0a1 16:32:46 INFO - mozversion platform_buildid: 20170329152123 16:32:46 INFO - mozversion platform_changeset: fc2eda92be4091d5ea0828f51fe4aaee41ed8319 16:32:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:32:46 INFO - mozversion platform_version: 55.0a1 16:32:46 INFO - using testdate: 1490830366 16:32:46 INFO - actual date: 1490830366 16:32:46 INFO - starting webserver on 'localhost:54074' 16:32:46 INFO - SUITE-START | Running 2 tests 16:32:46 INFO - TEST-START | dromaeo_css 16:32:46 INFO - operating with platform_type : mac_ 16:32:46 INFO - Initialising browser for dromaeo_css test... 16:32:46 INFO - TEST-INFO | started process 1965 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpgz7VQR/profile http://localhost:54074/getInfo.html) 16:32:48 INFO - PID 1965 | __metrics Screen width/height:1600/1200 16:32:48 INFO - PID 1965 | colorDepth:24 16:32:48 INFO - PID 1965 | Browser inner width/height: 1024/689 16:32:48 INFO - PID 1965 | __metrics 16:32:48 ERROR - PID 1965 | 1490830368754 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 16:32:48 INFO - PID 1965 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:32:48 INFO - PID 1965 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:32:49 INFO - TEST-INFO | 1965: exit 0 16:32:49 INFO - Browser initialized. 16:32:49 INFO - Running cycle 1/1 for dromaeo_css test... 16:32:49 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpgz7VQR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:33:01 INFO - PID 1967 | RSS: Main: 234684416 16:33:01 INFO - PID 1967 | 16:36:07 INFO - PID 1967 | Cycle 1(1): loaded http://localhost:54074/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54074/tests/dromaeo/cssquery-ext.html) 16:36:09 INFO - PID 1967 | RSS: Main: 373391360 16:36:09 INFO - PID 1967 | 16:37:51 INFO - PID 1967 | ************************* 16:37:51 INFO - PID 1967 | A coding exception was thrown and uncaught in a Task. 16:37:51 INFO - PID 1967 | 16:37:51 ERROR - PID 1967 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:37:51 INFO - PID 1967 | Full stack: 16:37:51 INFO - PID 1967 | ************************* 16:37:51 INFO - PID 1967 | ************************* 16:37:51 INFO - PID 1967 | A coding exception was thrown and uncaught in a Task. 16:37:51 INFO - PID 1967 | 16:37:51 ERROR - PID 1967 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:37:51 INFO - PID 1967 | Full stack: 16:37:51 INFO - PID 1967 | ************************* 16:37:51 INFO - PID 1967 | ************************* 16:37:51 INFO - PID 1967 | A coding exception was thrown and uncaught in a Task. 16:37:51 INFO - PID 1967 | 16:37:51 ERROR - PID 1967 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:37:51 INFO - PID 1967 | Full stack: 16:37:51 INFO - PID 1967 | ************************* 16:37:51 INFO - PID 1967 | ************************* 16:37:51 INFO - PID 1967 | A coding exception was thrown and uncaught in a Task. 16:37:51 INFO - PID 1967 | 16:37:51 ERROR - PID 1967 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:37:51 INFO - PID 1967 | Full stack: 16:37:51 INFO - PID 1967 | ************************* 16:39:12 INFO - PID 1967 | Cycle 1(1): loaded http://localhost:54074/tests/dromaeo/cssquery-ext.html (next: http://localhost:54074/tests/dromaeo/cssquery-jquery.html) 16:39:13 INFO - PID 1967 | RSS: Main: 369971200 16:39:13 INFO - PID 1967 | 16:42:15 INFO - PID 1967 | Cycle 1(1): loaded http://localhost:54074/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54074/tests/dromaeo/cssquery-mootools.html) 16:42:16 INFO - PID 1967 | RSS: Main: 344969216 16:42:16 INFO - PID 1967 | 16:45:18 INFO - PID 1967 | Cycle 1(1): loaded http://localhost:54074/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54074/tests/dromaeo/cssquery-prototype.html) 16:45:19 INFO - PID 1967 | RSS: Main: 357474304 16:45:19 INFO - PID 1967 | 16:48:23 INFO - PID 1967 | Cycle 1(1): loaded http://localhost:54074/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54074/tests/dromaeo/cssquery-yui.html) 16:48:24 INFO - PID 1967 | RSS: Main: 338989056 16:48:24 INFO - PID 1967 | 16:51:27 INFO - PID 1967 | Cycle 1(1): loaded http://localhost:54074/tests/dromaeo/cssquery-yui.html (next: http://localhost:54074/tests/dromaeo/cssquery-dojo.html) 16:51:27 INFO - PID 1967 | RSS: Main: 529629184 16:51:27 INFO - PID 1967 | 16:51:27 INFO - PID 1967 | __start_tp_report 16:51:27 INFO - PID 1967 | _x_x_mozilla_page_load 16:51:27 INFO - PID 1967 | _x_x_mozilla_page_load_details 16:51:27 INFO - PID 1967 | |i|pagename|runs| 16:51:27 INFO - PID 1967 | |0;dojo.html;3171.4132885416197;3485.8082805445692;3490.7084378779846;3496.6328535503753;3551.396262635352;6034.215551978247;6099.612447337997;6211.751529938797;6226.097215903697;6230.5638605297645;6138.909559458821;6172.321044685081;6227.221597300338;6241.906371404421;6258.561900666955;5059.463916716152;5385.703786291752;5565.554755619561;5572.888542229153;5580.302462192226;5104.412992505853;5366.738816378148;5669.518090962273;5704.828855134353;5718.428157184269;4540;5453.3728621208675;5527.723613819293;5539.639923404966;5627.634203776742;5519.862003449905;5548.223248745179;5551.611387202897;5553.055980483327;5554.611177217596;5437.619366644337;5502.174673798962;5535.612507124503;5544.91682624761;5549.417311182348;6145.170401995718;6148.58582526017;6175.567710260284;6176.258848938111;6176.907346389808;6580.012998050331;6652.833679158011;6682.632455214961;6693.698783554752;6697.062411262427;6681.832954176184;6700.698468568878;6758.1890173179045;6763.830904227384;6776.728930842761;6146.555559443527;6250.9062364064575;6325.240971083499;6331.271903731038;6352.841178970562;6725.630090345078;6781.118454600969;6889.827754306082;6897.655117244118;6903.930960690329;6343.968280158662;6422.3577642235405;6424.871502569922;6506.154199953976;6530.510211734139;6740.595564266062;6747.460203183831;6758.05387245786;6758.155230596176;6769.830754231184;6622.96688516564;6645.169353830771;6649.434798042221;6654;6667.099941507931;5363.5440987516495;6476.514261430411;6477.384648458389;6484.1894763154605;6667.899981500282;5222.3733152022005;5302.92045619316;5316.043112239757;5323.148296272655;5323.920141197885;4675.906481870412;4714.57568818808;4757.95242047582;4800.66395352322;4806.375171227718;6147.846303842351;6170.660613666291;6236.750529978841;6278.560500764994;6319.58922670025;6773.593584384953;6778.356056174655;6780.864382712417;6783.423409010289;6794.286599907037;6287.622742635456;6289.905651415233;6303.527235457359;6308.8422789429715;6317.842053948688;5355.410904800468;5457.508824205841;5762.942370576325;5848.122781582797;5961.254843144607;6777.423918966943;6789.558678685869;6795.490338224752;6798;6799.150106236721;6499.5775274607;6576.8355791105605;6578.473722102316;6579.638119903451;6601.207855057231;6948.478864085113;6954.582725036312;6960.443164546723;6964.373206411447;6970.546914450544;6560.343965603402;6614.404703576702;6616.437602803816;6658.43403310724;6674.799756007327;6411.102445657705;6520.739170433129;6523.6738163092605;6621.900671490028;6741.1236539249585;6496.155499784998;6542.476601871934;6558.8360290993105;6567.44176744982;6585.769498067544;6533.085368048382;6538.084668146368;6546.214454265519;6552.213734351908;6556.147700798866;6237.345078766664;6296.307406185224;6377.649229292433;6413.326600706859;6419.2296924369375;6393.584417012879;6457.4511166551365;6501.08984742127;6518.315576864361;6526;6371.076193951943;6380.712867921047;6404.743810247538;6406.295307516077;6409.583377080475 16:51:27 INFO - PID 1967 | |1;ext.html;791.8812178173273;815.1888870573778;1123.0285802780593;1143.371145869772;1144.6852115668185;36722.44916326257;37487.06282342943;37503.43744843829;37535.436968445494;37554.624453755525;38848.611513884855;39237.01907452315;39258.60741392592;39287;39357.62248321309;6030.155778191057;8354.999999999985;8496.107908669594;8507.95746021269;8517.53153576553;6134.2332208473945;6172.629642221458;6206.7827626033095;6209.658468784215;8270.214329638704;8493.36299777516;8527.914720852774;8529.232369086782;8535.146485351446;8585.570721463933;8438.746837594881;8501.617427215728;8506.362022848249;8510.48937063775;8532.658693652247;8330.791730206733;8397.37019723517;8402.78993025173;8409.075001749863;8444.451110677786;37286.813565931996;37517.24965500702;37553.43669844954;37615.62384376176;37642.24715505674;2487.5895477246413;2926.2538052796613;2990.5364668476204;3001.83489908055;3033.8331391773445;26885.865570672217;26928.326791830164;26975.32561685954;27081.593776093374;27367.86316068427;2756.421151558175;2756.807023508355;2757.8621068946672;2781.902633407841;2797.3286411261356;294087;294576;295051;295089;295482.99999999785;38791.41812872809;39799.20401591952;49142.26286605704;49226.75386623044;49328;42191.15617687629;49834;49878.501214988115;50564.74717626388;50616.49383506191;12241.816372754416;13873.028887977745;13985.440582376794;14024.509142180179;14054.71890562183;9935.304528682922;11093.944530277297;11296.604618838466;11299.604513841976;11301.49143288554;11839.17125801203;11864.940675296568;11885.762284754257;11895.762084758257;11968.281903085843;5436.836894893159;5439.646422982493;5442.863928401822;5461.9453805462235;5478.205660179331;965.7440778193644;968.2157452463528;971.7036303927229;984.9704508864745;991.8859331176862;23874.88062559676;23941.880290598434;28452.71547284542;28921;29634.407311854073;1045.2474218562622;1046.1682962045193;1084.3927400655657;1084.6583326252205;1096.7477480179443;11673.649790506299;12020.579279725167;12051.276923384625;15139.167345796088;15267.23663816827;376622;377546;378031.2196878051;378106;379194;52495.73752131215;52690.47309526932;52704.736476317375;52743.73628131835;52874.471255287724;1443.8411774704564;1471.1026273972868;1499.400239904069;1503.7218114648824;1516.5601975427223;25823.354416139748;26730.866345668146;26881.059162929203;27536.173914781786;27579.586306205823;36647.63352366442;36726.44910326294;36757.816210918776;36771.44842827306;36780.26439471249;339325;364082;365320;366580;366584;25298.620520692573;30333.241668958453;30558.777648893796;30675.539866901567;30759.538606920465;38818.41772373473;38907.805460972515;39145.804270978464;39153.608463915;40047.19905601814;27860.16419507418;27877;28795.568066479438;28826.42347153087;28868.27829304284;23254.76745232526;27551.31121721893;27713.307167320978;28239.435211295247;28734.712652873204;32887.506687400186;32932.34135317328;33064;33073.33853322872;35177.64822351744;27232.863835680695;29479.55780663249;30380;30488.39023219479;30701.846490767402;21859.344219673436;22653.98057087402;22809.657855131853;23476.356655033982;23858 16:51:27 INFO - PID 1967 | |2;jquery.html;387.4014647369813;417.7660510114336;429.15456550595337;431.8941859244485;445.594508996813;15661.608459788511;21473.892630536844;21775.911204439795;22242.88878555607;22530.77469225307;22018.256896327624;22125.557488850212;22219.333419997387;22282.220122295723;22362.776372236272;5806.59353845231;5896.852578685546;5908.61593996391;6018.759249630009;6120.081987701836;3576.5886923003795;3778.338790711628;5834.5040671542965;5952.761889524414;5957.165996760457;3724.255148970217;3755.830987605564;3759.8684046058393;3771.2268984858038;3823.885283441501;5955.255593050891;5961.076033214858;5975.282966044103;5975.492083172936;5987.850303742398;5089.211172268304;5234.6335756497065;5294.761735721863;5298.602604804617;5303.390110137345;25514.617280740804;25562.488750224893;25658.615120773204;25739.742602573922;25920;1993.0931426201043;1995.8702684325472;2017.1326329678232;2030.979690203094;2082.9270975515783;15095.698086038328;15649.29578168985;16169.595760105974;16975.91512042444;17583.73624395635;1483.6216764725034;1710.7604935309066;1764.1532064608932;1777.1380880273057;1803.6933721267417;142452.2877385617;155001;167568.32431675648;169284;171290.43064354046;17369.91315043442;23028.884855575616;23340.64989025166;23523.882380587987;23728;15500;16061.598460038358;16075;41426.75719728413;41633.79183104065;8755.956220218859;8856.468611883307;8903;8930.017698053209;9029.367944243972;8487.151284871463;8518.70184543538;8665.133486651284;8666.78333041679;8701.390902636882;6995.615241161683;7156.854903215564;7528.623568821537;7563.432258193389;7710.884336734841;2166.4150679316813;2169.804717575426;2173.3479956013134;2176.2927048708916;2300.2869110575775;904.7153042679411;906.5331354352513;909.8089401225684;941.5339406993583;953.1612181280567;16254;17976.730349044774;19401.932893690984;20149.294774682814;20604.484887877923;877.9912200878038;931.3713243560569;953.1707414549386;965.7440778193784;972.5429048347265;10069.748256293651;10226.897731022595;10279.743006424898;10331.380117192992;10363.378197308035;685852;689801.4278001083;700078;711587;719820;152142.47857521503;152388.238058809;153298.90361512103;156915;157317;975.7658162041201;1107.7618312062784;1135.4266095621824;1156.0693641618577;1161.8838116188313;17228.569285767957;19736.506587335432;20803.791962080184;20839.374818755463;20922.267720629705;54784.630384240714;57261;59098.40901591101;60423.39576604178;60727.69636151791;74450.62774686309;76007.23992760223;76010.61994689991;76132;76632.23367766474;8165.183481651788;8193.83612327762;8526.531040792568;8605.741827744927;8626.051134375339;12735.808962865749;13125.934370328087;13221.801672975105;13279.468821247043;13624.114432562248;10630.681079567625;11010.944945275221;11397.259178153394;11448.026917712088;11614.825777613512;10460.895391045991;10655.89344106549;10845.349279043283;10884.782304354027;10930.562777489129;11599.246049006788;11892.940535297268;11979.401029948642;11984.04127669767;12064.155509114278;8263.091059983517;8387.748367548984;8418.116097809641;8475.406721529434;8489.575521223791;11047.613333533287;11183.496742646437;11495.252808567417;11536.307821530734;11624.476898539755 16:51:27 INFO - PID 1967 | |3;mootools.html;1254.385351177923;1364.6656569140562;1417.7377185220735;1439.596912864397;1443.451488434395;10640.893591064087;11008.064314533265;11074.446277686104;11086.390248536327;11088.667339979793;10624;10795.460226988658;10980.945095274512;11013.889861101386;11020.834687479673;2443.2303824295336;2485.893777269116;2487.465194983078;2489.153687746166;2493.5012997400504;2408.277516744978;2417.709874815016;2473.6907886514186;2474.8020158387376;2476.727559968402;2350.21267875262;2457.58221102413;2463.2610216934936;2469.3085935937966;2471.1845091119976;2427.1141033522813;2449.1428000199967;2452.8037756979397;2475.504899020203;2489.9253022409357;2436.171701621457;2465.3097132802845;2473.0849585653245;2473.295110893405;2475.207933461275;10899.1280697544;11125;11125.777484450266;11129.054030407426;11147.108231341474;6221.160143380676;6461;6469.061986012049;6473.643949582771;6477.417032467053;3995.1210733638554;4058.1072164123843;4071.4503542021744;4123.422720819088;4153.522344930311;1159.6231224851956;1164.5381277123372;1199.5741511763372;1219.3415555600054;1221.9450124744408;14038.613435715954;14486.20325882076;15074.397024118913;15299.617509562238;15487;4468.955310446919;4532.478764942074;5376.73116344189;5402.108652072364;5420.593455490854;11497.425128743529;11499.137564682682;11512.424378781028;11518.308901465938;11521.308721476738;5377.516023557899;5591.41290164535;6278.874422511615;6300.432961033437;6302.338254483305;5739.368669446358;5814.883702325929;6023.036314189709;6071.271447426248;6084.5132389408745;4178.498580170338;4253.80857861397;4275.700700950903;4315.417418648505;4335.501417336984;1078.309881675728;1244.4026867103842;1247.1394737631006;1260.6281147061457;1261.4386597964;765.1736124985114;765.636322746702;785.1912530094012;793.6785601831267;800.0918956983749;5994.550408719363;6064.454199122012;6221.968890155611;6263.498920086382;6370.362963703686;560.5963706131661;569.4504802865255;577.4398833131855;580.5790801668757;585.5666806563113;4166.541680415152;4263.680223983151;4351.522684897452;4400.75795831226;4432.512423633398;13674.453021879013;14377.424903003972;14390.712185756227;14405;14425.062370945854;13814.171149731048;14146.717065658835;14297.857021429652;14312.642183945276;14316.568343165809;10330;10445.738856528647;10449;10451.16390688743;10460.68617941463;6381.106645069601;6431.871362572816;6530.118434011348;6620.635865027277;6629.535932484872;12238.08214383907;12606.117571769888;12617.747645047231;12626.179298345578;12642.2414655121;11927.76144477123;12098.09264305163;12373.443195056425;12948;12976.755811047238;7993.92006079955;8201.384896132695;8415.116412776815;8503.532305723245;8511.319094472303;10245.897541024495;10374.066334029974;10479.738006549896;10491.108255798343;10495.317804342692;8761;8766.167214114648;8791.560421979004;8852.601632926662;8857;8168.591570421337;8416.15838416178;8433.451825631315;8442.409031367746;8450.746477605682;7796.9220307796195;7870.094939082079;7981.8802717958115;7987.201279871966;7990.680372785024;5701.828945131652;5989.52083833301;6031.788887388921;6181.041938499539;6258.561900666909;5835.766569337179;6079.696015199134;6167.568270221221;6181.258249010148;6214.067889816563 16:51:27 INFO - PID 1967 | |4;prototype.html;377.61294672960213;387.5756047128394;402.18557421222033;405.42834603209445;406.4289673009423;5325.281087053247;5499.147632117023;6000.75996960121;6318.715657795398;6323.936760632387;5552.890381386416;5950.880982380349;6019.488343490809;6033.5474839387;6164.6301221926715;3639.8726044588443;3912.921741565167;4719.315699223611;4776.474587795331;4798.976005119969;4191.643710284629;4619.676622636417;4621.722696638196;4734.55021772931;4828.589569886564;4671.789769460383;4701.7414042227665;4731.834385796497;4749.477557468693;4755.6908800928;3926.725129240954;4716.151092803294;4745.76271186441;4752.312928910256;4755.738434386108;3755.6432138946757;3801.6958643308744;3938.916797880606;3939.975606342343;3955.9714474236616;5726.541876649858;5957.514853810526;5993.392874099737;6013.488853447462;6030;4019.658329042035;5144.8616344030115;5392;5413.702246376448;5416.972915135439;6046.244219472566;6180.474659653934;6542.509311801634;6583.473322134218;6597.505187110986;3875.9418608720894;4373.693841431101;4382.211201983641;4385.890352741175;4389.9025243689075;5796.333421656479;6528.281888992207;6932.202796678396;6938.1553563038915;7032.789016329519;4325.935110973338;4411.117776444659;4411.691181617321;4423.024097889874;4429.335599660012;4474.217012022883;4480.731156130578;4495.483019452805;4497.910041799146;4499.752513611717;4286.849960251377;4477.820887164542;4815.181419158752;5350.38470575881;5366.7048312343195;6312;6564.606123632598;6602.372774586406;6618.801435956929;6667.499937504706;1272.287518989369;1405.8523854995078;1469.8162729658793;1476.3430273528177;1477.6010477171103;865.2342676731013;879.9692010779592;942.241495596038;959.2949182351106;966.4877614864133;5312.5218730314455;6162.784302549389;6307.96846015767;6331.176946996861;6343.809685709436;6554.442872355903;6630.734770609218;6750.662466876636;6865.931340686529;6888.035675005503;5559.359304330492;6220.298922141319;6221.751129954841;6256.186695729526;6268.119564130834;2859.8999035033676;3121.406932682783;3247.967520324814;3565.3047655707405;3572.821358932043;7065.4074851346;7070.505064645528;7086.822829429306;7108.3602475777425;7121.857562848818;6737.326267373322;6879.552829066095;6897.930820722796;6966.9303306967295;6979.953007048984;5767.740451679684;6692.99605059245;6948.6178260195165;6974.860502790018;7018.298170183043;5007.923296491272;5602.579806514609;6129.835331287041;6678.599284042779;6737.59574425536;6698.363655452724;6746.86506269862;6756.560823546453;6771.322867713189;6823.727050917908;6627.204735431779;6729.69716362755;6865.794026179223;7006.719731210695;7095.97108419266;3654.196076863086;4384.145091707086;4393.20922234001;4444.31113177458;4444.422235103697;5931.406859314034;6575.50683698715;6786.86213842638;6836.111305530249;6849.315068493111;6049.818505444844;6295.375385550648;6468.579542329733;6520.608763474207;6558.213014438298;5669.858253543694;6040.063790112539;6280.403361680633;6417.021446703074;6422.614643121427;5253.317068781034;6487.54587178893;6488.837779055561;6502.594040205155;6515.315891831289;6399.408239662944;6547.461346583645;6600.4127131643045;6625.720268466946;6634.009797060959;5351.518363347317;5717.771289148387;5815.563832712648;6310.085037669604;6441.420272175526 16:51:27 INFO - PID 1967 | |5;yui.html;712.4122598855942;724.6666533394638;735.6027745017689;741.1106671993606;766.2031487253257;15735.449259275912;16034.7594786078;16282.755758663614;16352;16384.99999999997;14535.49125780598;15348.155851428168;15458.768118478203;15468.922655386736;15480.458183963563;945.7493764152505;950.2540505703024;956.1967946924582;956.6508224498077;956.7895063086145;955.9426434413923;956.93779904306;961.9759506012335;986.2356673577963;1006.3961623026199;1054.056619325703;1056.8678915135608;1057.6404022632303;1058.2962330050507;1058.5818601652343;1052.1319911073394;1052.1582733812995;1052.6578861869923;1053.1048608682597;1053.5469748008354;700.4431476975766;784.9293563579248;785.9214078592153;786.0724345272577;791.5409062743595;16101.516954491266;16535.751963720555;16579.834201657948;16689.49931502057;16774;14516.491922782652;14662;14676;14697;14743;11697.94151029248;11846.703832404173;11902.58340958071;12093.395330233541;12345.69135771604;2998.7900846940724;3029.0618100442534;3089.81461112334;3127.8592463339205;3155.0692545699076;91415.08584914132;112044;120123.78638349775;120247;120471.99999999913;9545.990624473543;11396.316221026766;11489.54041838317;11511.827322590168;11602.419879006016;24683.01267949262;24890.751092489205;25138.748612513642;25171;25249.74750252474;7803.013714991015;7857.410694197958;7916.406269529808;7936.3254123399;8067.596620168968;4190.727602705814;4196.181744559782;4209.326507758805;4226.535081141072;4246.320588705792;4108.4864391951005;4181.581841815794;4186.727862688916;4220.134872351161;4224.345226489899;783.3693876429529;783.6669415498391;784.5606460382204;792.84935862186;798.6565776716068;561.5535649158928;563.5322682173786;573.7131434282859;582.4146732533776;595.5531372888414;9628.71113866585;9854.950725246472;10058.647947321808;10102.69691909244;10113.443760593238;490.5437942713311;543.0876128104821;550.2736387967924;552.7899546771661;575.2579172866955;4627.467841198305;4729.976350118227;4768.403949506312;4768.732356632902;4812.326274321597;91437.54281228551;95960.52019739996;97728;97990.10049497496;98818;73951.63024184844;88250;95280.37756979425;96355;97604;80919.59540202378;81559.5922020386;82539;82911.58544207361;84334.15665843386;8417.07412184647;8872.467651940904;9132.771680708036;9421.38760980534;9567.904320956979;38988.61011389963;40570.79714601408;40906;41284.38073428841;41926.95182620459;57117.85764284774;60604.181874543836;71770;71855;72555.27444725485;13551.525696600966;13601.115927464683;13978.510752123628;14138.152043762155;14158;12521.812172817234;15023.248837558296;15107.974370045986;15209.695806084037;15363.923180384027;11713.472893719632;13608.455661773418;13697.931510342385;13712.862871371159;13765.518206862711;11842.157643311895;11971.401429928295;13691.251066122057;14020.929895350459;14342.282885855875;13037.60369579271;13444.731105378034;13633.659158521117;13832.654183645489;14258.358373872994;9814.165795907427;10056.597736090474;10146.49267536635;10237.436940968319;10424.843627345444;9380.906190938276;9680.322377433793;9939.721739302564;11142.554297828321;11206.775864482828 16:51:27 INFO - PID 1967 | __end_tp_report 16:51:27 INFO - PID 1967 | __start_cc_report 16:51:27 INFO - PID 1967 | _x_x_mozilla_cycle_collect,2134 16:51:27 INFO - PID 1967 | __end_cc_report 16:51:27 INFO - PID 1967 | __startTimestamp1490831487380__endTimestamp 16:51:27 INFO - PID 1967 | 16:51:27 INFO - PID 1967 | ------- Summary: start ------- 16:51:27 INFO - PID 1967 | Number of tests: 6 16:51:27 INFO - PID 1967 | 16:51:27 INFO - PID 1967 | [#0] dojo.html Cycles:165 Average:6146.68 Median:6393.58 stddev:733.28 (11.5%) stddev-sans-first:697.39 16:51:27 INFO - PID 1967 | Values: 3171.4 3485.8 3490.7 3496.6 3551.4 6034.2 6099.6 6211.8 6226.1 6230.6 6138.9 6172.3 6227.2 6241.9 6258.6 5059.5 5385.7 5565.6 5572.9 5580.3 5104.4 5366.7 5669.5 5704.8 5718.4 4540.0 5453.4 5527.7 5539.6 5627.6 5519.9 5548.2 5551.6 5553.1 5554.6 5437.6 5502.2 5535.6 5544.9 5549.4 6145.2 6148.6 6175.6 6176.3 6176.9 6580.0 6652.8 6682.6 6693.7 6697.1 6681.8 6700.7 6758.2 6763.8 6776.7 6146.6 6250.9 6325.2 6331.3 6352.8 6725.6 6781.1 6889.8 6897.7 6903.9 6344.0 6422.4 6424.9 6506.2 6530.5 6740.6 6747.5 6758.1 6758.2 6769.8 6623.0 6645.2 6649.4 6654.0 6667.1 5363.5 6476.5 6477.4 6484.2 6667.9 5222.4 5302.9 5316.0 5323.1 5323.9 4675.9 4714.6 4758.0 4800.7 4806.4 6147.8 6170.7 6236.8 6278.6 6319.6 6773.6 6778.4 6780.9 6783.4 6794.3 6287.6 6289.9 6303.5 6308.8 6317.8 5355.4 5457.5 5762.9 5848.1 5961.3 6777.4 6789.6 6795.5 6798.0 6799.2 6499.6 6576.8 6578.5 6579.6 6601.2 6948.5 6954.6 6960.4 6964.4 6970.5 6560.3 6614.4 6616.4 6658.4 6674.8 6411.1 6520.7 6523.7 6621.9 6741.1 6496.2 6542.5 6558.8 6567.4 6585.8 6533.1 6538.1 6546.2 6552.2 6556.1 6237.3 6296.3 6377.6 6413.3 6419.2 6393.6 6457.5 6501.1 6518.3 6526.0 6371.1 6380.7 6404.7 6406.3 6409.6 16:51:27 INFO - PID 1967 | 16:51:27 INFO - PID 1967 | [#1] ext.html Cycles:180 Average:47976.44 Median:186199.17 stddev:91459.94 (49.1%) stddev-sans-first:91647.90 16:51:27 INFO - PID 1967 | Values: 791.9 815.2 1123.0 1143.4 1144.7 36722.4 37487.1 37503.4 37535.4 37554.6 38848.6 39237.0 39258.6 39287.0 39357.6 6030.2 8355.0 8496.1 8508.0 8517.5 6134.2 6172.6 6206.8 6209.7 8270.2 8493.4 8527.9 8529.2 8535.1 8585.6 8438.7 8501.6 8506.4 8510.5 8532.7 8330.8 8397.4 8402.8 8409.1 8444.5 37286.8 37517.2 37553.4 37615.6 37642.2 2487.6 2926.3 2990.5 3001.8 3033.8 26885.9 26928.3 26975.3 27081.6 27367.9 2756.4 2756.8 2757.9 2781.9 2797.3 294087.0 294576.0 295051.0 295089.0 295483.0 38791.4 39799.2 49142.3 49226.8 49328.0 42191.2 49834.0 49878.5 50564.7 50616.5 12241.8 13873.0 13985.4 14024.5 14054.7 9935.3 11093.9 11296.6 11299.6 11301.5 11839.2 11864.9 11885.8 11895.8 11968.3 5436.8 5439.6 5442.9 5461.9 5478.2 965.7 968.2 971.7 985.0 991.9 23874.9 23941.9 28452.7 28921.0 29634.4 1045.2 1046.2 1084.4 1084.7 1096.7 11673.6 12020.6 12051.3 15139.2 15267.2 376622.0 377546.0 378031.2 378106.0 379194.0 52495.7 52690.5 52704.7 52743.7 52874.5 1443.8 1471.1 1499.4 1503.7 1516.6 25823.4 26730.9 26881.1 27536.2 27579.6 36647.6 36726.4 36757.8 36771.4 36780.3 339325.0 364082.0 365320.0 366580.0 366584.0 25298.6 30333.2 30558.8 30675.5 30759.5 38818.4 38907.8 39145.8 39153.6 40047.2 27860.2 27877.0 28795.6 28826.4 28868.3 23254.8 27551.3 27713.3 28239.4 28734.7 32887.5 32932.3 33064.0 33073.3 35177.6 27232.9 29479.6 30380.0 30488.4 30701.8 21859.3 22654.0 22809.7 23476.4 23858.0 16:51:27 INFO - PID 1967 | 16:51:27 INFO - PID 1967 | [#2] jquery.html Cycles:180 Average:40805.72 Median:23625.94 stddev:117774.08 (498.5%) stddev-sans-first:118065.37 16:51:27 INFO - PID 1967 | Values: 387.4 417.8 429.2 431.9 445.6 15661.6 21473.9 21775.9 22242.9 22530.8 22018.3 22125.6 22219.3 22282.2 22362.8 5806.6 5896.9 5908.6 6018.8 6120.1 3576.6 3778.3 5834.5 5952.8 5957.2 3724.3 3755.8 3759.9 3771.2 3823.9 5955.3 5961.1 5975.3 5975.5 5987.9 5089.2 5234.6 5294.8 5298.6 5303.4 25514.6 25562.5 25658.6 25739.7 25920.0 1993.1 1995.9 2017.1 2031.0 2082.9 15095.7 15649.3 16169.6 16975.9 17583.7 1483.6 1710.8 1764.2 1777.1 1803.7 142452.3 155001.0 167568.3 169284.0 171290.4 17369.9 23028.9 23340.6 23523.9 23728.0 15500.0 16061.6 16075.0 41426.8 41633.8 8756.0 8856.5 8903.0 8930.0 9029.4 8487.2 8518.7 8665.1 8666.8 8701.4 6995.6 7156.9 7528.6 7563.4 7710.9 2166.4 2169.8 2173.3 2176.3 2300.3 904.7 906.5 909.8 941.5 953.2 16254.0 17976.7 19401.9 20149.3 20604.5 878.0 931.4 953.2 965.7 972.5 10069.7 10226.9 10279.7 10331.4 10363.4 685852.0 689801.4 700078.0 711587.0 719820.0 152142.5 152388.2 153298.9 156915.0 157317.0 975.8 1107.8 1135.4 1156.1 1161.9 17228.6 19736.5 20803.8 20839.4 20922.3 54784.6 57261.0 59098.4 60423.4 60727.7 74450.6 76007.2 76010.6 76132.0 76632.2 8165.2 8193.8 8526.5 8605.7 8626.1 12735.8 13125.9 13221.8 13279.5 13624.1 10630.7 11010.9 11397.3 11448.0 11614.8 10460.9 10655.9 10845.3 10884.8 10930.6 11599.2 11892.9 11979.4 11984.0 12064.2 8263.1 8387.7 8418.1 8475.4 8489.6 11047.6 11183.5 11495.3 11536.3 11624.5 16:51:27 INFO - PID 1967 | 16:51:27 INFO - PID 1967 | [#3] mootools.html Cycles:180 Average:6816.53 Median:2486.68 stddev:4227.83 (170.0%) stddev-sans-first:4219.03 16:51:27 INFO - PID 1967 | Values: 1254.4 1364.7 1417.7 1439.6 1443.5 10640.9 11008.1 11074.4 11086.4 11088.7 10624.0 10795.5 10980.9 11013.9 11020.8 2443.2 2485.9 2487.5 2489.2 2493.5 2408.3 2417.7 2473.7 2474.8 2476.7 2350.2 2457.6 2463.3 2469.3 2471.2 2427.1 2449.1 2452.8 2475.5 2489.9 2436.2 2465.3 2473.1 2473.3 2475.2 10899.1 11125.0 11125.8 11129.1 11147.1 6221.2 6461.0 6469.1 6473.6 6477.4 3995.1 4058.1 4071.5 4123.4 4153.5 1159.6 1164.5 1199.6 1219.3 1221.9 14038.6 14486.2 15074.4 15299.6 15487.0 4469.0 4532.5 5376.7 5402.1 5420.6 11497.4 11499.1 11512.4 11518.3 11521.3 5377.5 5591.4 6278.9 6300.4 6302.3 5739.4 5814.9 6023.0 6071.3 6084.5 4178.5 4253.8 4275.7 4315.4 4335.5 1078.3 1244.4 1247.1 1260.6 1261.4 765.2 765.6 785.2 793.7 800.1 5994.6 6064.5 6222.0 6263.5 6370.4 560.6 569.5 577.4 580.6 585.6 4166.5 4263.7 4351.5 4400.8 4432.5 13674.5 14377.4 14390.7 14405.0 14425.1 13814.2 14146.7 14297.9 14312.6 14316.6 10330.0 10445.7 10449.0 10451.2 10460.7 6381.1 6431.9 6530.1 6620.6 6629.5 12238.1 12606.1 12617.7 12626.2 12642.2 11927.8 12098.1 12373.4 12948.0 12976.8 7993.9 8201.4 8415.1 8503.5 8511.3 10245.9 10374.1 10479.7 10491.1 10495.3 8761.0 8766.2 8791.6 8852.6 8857.0 8168.6 8416.2 8433.5 8442.4 8450.7 7796.9 7870.1 7981.9 7987.2 7990.7 5701.8 5989.5 6031.8 6181.0 6258.6 5835.8 6079.7 6167.6 6181.3 6214.1 16:51:27 INFO - PID 1967 | 16:51:27 INFO - PID 1967 | [#4] prototype.html Cycles:180 Average:5278.79 Median:6016.49 stddev:1687.49 (28.0%) stddev-sans-first:1651.64 16:51:27 INFO - PID 1967 | Values: 377.6 387.6 402.2 405.4 406.4 5325.3 5499.1 6000.8 6318.7 6323.9 5552.9 5950.9 6019.5 6033.5 6164.6 3639.9 3912.9 4719.3 4776.5 4799.0 4191.6 4619.7 4621.7 4734.6 4828.6 4671.8 4701.7 4731.8 4749.5 4755.7 3926.7 4716.2 4745.8 4752.3 4755.7 3755.6 3801.7 3938.9 3940.0 3956.0 5726.5 5957.5 5993.4 6013.5 6030.0 4019.7 5144.9 5392.0 5413.7 5417.0 6046.2 6180.5 6542.5 6583.5 6597.5 3875.9 4373.7 4382.2 4385.9 4389.9 5796.3 6528.3 6932.2 6938.2 7032.8 4325.9 4411.1 4411.7 4423.0 4429.3 4474.2 4480.7 4495.5 4497.9 4499.8 4286.8 4477.8 4815.2 5350.4 5366.7 6312.0 6564.6 6602.4 6618.8 6667.5 1272.3 1405.9 1469.8 1476.3 1477.6 865.2 880.0 942.2 959.3 966.5 5312.5 6162.8 6308.0 6331.2 6343.8 6554.4 6630.7 6750.7 6865.9 6888.0 5559.4 6220.3 6221.8 6256.2 6268.1 2859.9 3121.4 3248.0 3565.3 3572.8 7065.4 7070.5 7086.8 7108.4 7121.9 6737.3 6879.6 6897.9 6966.9 6980.0 5767.7 6693.0 6948.6 6974.9 7018.3 5007.9 5602.6 6129.8 6678.6 6737.6 6698.4 6746.9 6756.6 6771.3 6823.7 6627.2 6729.7 6865.8 7006.7 7096.0 3654.2 4384.1 4393.2 4444.3 4444.4 5931.4 6575.5 6786.9 6836.1 6849.3 6049.8 6295.4 6468.6 6520.6 6558.2 5669.9 6040.1 6280.4 6417.0 6422.6 5253.3 6487.5 6488.8 6502.6 6515.3 6399.4 6547.5 6600.4 6625.7 6634.0 5351.5 5717.8 5815.6 6310.1 6441.4 16:51:27 INFO - PID 1967 | 16:51:27 INFO - PID 1967 | [#5] yui.html Cycles:180 Average:20552.73 Median:22507.24 stddev:29660.79 (131.8%) stddev-sans-first:29706.58 16:51:27 INFO - PID 1967 | Values: 712.4 724.7 735.6 741.1 766.2 15735.4 16034.8 16282.8 16352.0 16385.0 14535.5 15348.2 15458.8 15468.9 15480.5 945.7 950.3 956.2 956.7 956.8 955.9 956.9 962.0 986.2 1006.4 1054.1 1056.9 1057.6 1058.3 1058.6 1052.1 1052.2 1052.7 1053.1 1053.5 700.4 784.9 785.9 786.1 791.5 16101.5 16535.8 16579.8 16689.5 16774.0 14516.5 14662.0 14676.0 14697.0 14743.0 11697.9 11846.7 11902.6 12093.4 12345.7 2998.8 3029.1 3089.8 3127.9 3155.1 91415.1 112044.0 120123.8 120247.0 120472.0 9546.0 11396.3 11489.5 11511.8 11602.4 24683.0 24890.8 25138.7 25171.0 25249.7 7803.0 7857.4 7916.4 7936.3 8067.6 4190.7 4196.2 4209.3 4226.5 4246.3 4108.5 4181.6 4186.7 4220.1 4224.3 783.4 783.7 784.6 792.8 798.7 561.6 563.5 573.7 582.4 595.6 9628.7 9855.0 10058.6 10102.7 10113.4 490.5 543.1 550.3 552.8 575.3 4627.5 4730.0 4768.4 4768.7 4812.3 91437.5 95960.5 97728.0 97990.1 98818.0 73951.6 88250.0 95280.4 96355.0 97604.0 80919.6 81559.6 82539.0 82911.6 84334.2 8417.1 8872.5 9132.8 9421.4 9567.9 38988.6 40570.8 40906.0 41284.4 41927.0 57117.9 60604.2 71770.0 71855.0 72555.3 13551.5 13601.1 13978.5 14138.2 14158.0 12521.8 15023.2 15108.0 15209.7 15363.9 11713.5 13608.5 13697.9 13712.9 13765.5 11842.2 11971.4 13691.3 14020.9 14342.3 13037.6 13444.7 13633.7 13832.7 14258.4 9814.2 10056.6 10146.5 10237.4 10424.8 9380.9 9680.3 9939.7 11142.6 11206.8 16:51:27 INFO - PID 1967 | -------- Summary: end -------- 16:51:27 INFO - PID 1967 | 16:51:28 INFO - TEST-INFO | 1967: exit 0 16:51:28 INFO - TEST-OK | dromaeo_css | took 1121865ms 16:51:28 INFO - TEST-START | kraken 16:51:28 INFO - operating with platform_type : mac_ 16:51:28 INFO - Initialising browser for kraken test... 16:51:28 INFO - TEST-INFO | started process 1970 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpd3Roqf/profile http://localhost:54074/getInfo.html) 16:51:30 INFO - PID 1970 | __metrics Screen width/height:1600/1200 16:51:30 INFO - PID 1970 | colorDepth:24 16:51:30 INFO - PID 1970 | Browser inner width/height: 1024/689 16:51:30 INFO - PID 1970 | __metrics 16:51:30 ERROR - PID 1970 | 1490831490648 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 16:51:30 INFO - PID 1970 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:51:30 INFO - PID 1970 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:51:31 INFO - TEST-INFO | 1970: exit 0 16:51:31 INFO - Browser initialized. 16:51:31 INFO - Running cycle 1/1 for kraken test... 16:51:31 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpd3Roqf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:51:43 INFO - PID 1971 | RSS: Main: 234610688 16:51:43 INFO - PID 1971 | 16:51:46 INFO - PID 1971 | Cycle 1(1): loaded http://localhost:54074/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54074/tests/kraken/driver.html?testName=audio-beat-detection) 16:51:47 INFO - PID 1971 | RSS: Main: 287424512 16:51:47 INFO - PID 1971 | 16:51:50 INFO - PID 1971 | Cycle 1(1): loaded http://localhost:54074/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54074/tests/kraken/driver.html?testName=audio-dft) 16:51:51 INFO - PID 1971 | RSS: Main: 286375936 16:51:51 INFO - PID 1971 | 16:51:54 INFO - PID 1971 | Cycle 1(1): loaded http://localhost:54074/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54074/tests/kraken/driver.html?testName=audio-fft) 16:51:55 INFO - PID 1971 | RSS: Main: 380321792 16:51:55 INFO - PID 1971 | 16:51:57 INFO - PID 1971 | Cycle 1(1): loaded http://localhost:54074/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54074/tests/kraken/driver.html?testName=audio-oscillator) 16:51:58 INFO - PID 1971 | RSS: Main: 286138368 16:51:58 INFO - PID 1971 | 16:52:00 INFO - PID 1971 | Cycle 1(1): loaded http://localhost:54074/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54074/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:52:01 INFO - PID 1971 | RSS: Main: 287043584 16:52:01 INFO - PID 1971 | 16:52:04 INFO - PID 1971 | Cycle 1(1): loaded http://localhost:54074/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54074/tests/kraken/driver.html?testName=imaging-darkroom) 16:52:04 INFO - PID 1971 | RSS: Main: 327733248 16:52:05 INFO - PID 1971 | 16:52:08 INFO - PID 1971 | Cycle 1(1): loaded http://localhost:54074/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54074/tests/kraken/driver.html?testName=imaging-desaturate) 16:52:08 INFO - PID 1971 | RSS: Main: 337543168 16:52:08 INFO - PID 1971 | 16:52:12 INFO - PID 1971 | Cycle 1(1): loaded http://localhost:54074/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54074/tests/kraken/driver.html?testName=json-parse-financial) 16:52:12 INFO - PID 1971 | RSS: Main: 342253568 16:52:12 INFO - PID 1971 | 16:52:14 INFO - PID 1971 | Cycle 1(1): loaded http://localhost:54074/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54074/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:52:15 INFO - PID 1971 | RSS: Main: 306294784 16:52:15 INFO - PID 1971 | 16:52:17 INFO - PID 1971 | Cycle 1(1): loaded http://localhost:54074/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54074/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:52:17 INFO - PID 1971 | RSS: Main: 306737152 16:52:17 INFO - PID 1971 | 16:52:19 INFO - PID 1971 | Cycle 1(1): loaded http://localhost:54074/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54074/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:52:20 INFO - PID 1971 | RSS: Main: 308244480 16:52:20 INFO - PID 1971 | 16:52:23 INFO - PID 1971 | Cycle 1(1): loaded http://localhost:54074/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54074/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:52:24 INFO - PID 1971 | RSS: Main: 328863744 16:52:24 INFO - PID 1971 | 16:52:27 INFO - PID 1971 | Cycle 1(1): loaded http://localhost:54074/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54074/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:52:28 INFO - PID 1971 | RSS: Main: 322973696 16:52:28 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | Cycle 1(1): loaded http://localhost:54074/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54074/tests/kraken/driver.html?testName=ai-astar) 16:52:30 INFO - PID 1971 | RSS: Main: 386674688 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | __start_tp_report 16:52:30 INFO - PID 1971 | _x_x_mozilla_page_load 16:52:30 INFO - PID 1971 | _x_x_mozilla_page_load_details 16:52:30 INFO - PID 1971 | |i|pagename|runs| 16:52:30 INFO - PID 1971 | |0;ai-astar;111;123;122;122;121;124;154;183;183;121 16:52:30 INFO - PID 1971 | |1;audio-beat-detection;167;149;105;200;113;146;157;151;148;122 16:52:30 INFO - PID 1971 | |2;audio-dft;207;209;214;210;208;214;209;208;205;210 16:52:30 INFO - PID 1971 | |3;audio-fft;84;64;63;102;68;76;91;93;82;64 16:52:30 INFO - PID 1971 | |4;audio-oscillator;96;74;96;74;76;76;90;76;75;90 16:52:30 INFO - PID 1971 | |5;imaging-gaussian-blur;93;94;94;93;115;138;95;95;92;90 16:52:30 INFO - PID 1971 | |6;imaging-darkroom;97;100;100;130;157;100;100;99;99;100 16:52:30 INFO - PID 1971 | |7;imaging-desaturate;100;102;98;102;101;100;101;103;102;101 16:52:30 INFO - PID 1971 | |8;json-parse-financial;67;65;65;64;65;65;65;65;65;66 16:52:30 INFO - PID 1971 | |9;json-stringify-tinderbox;58;87;104;108;72;64;63;63;65;64 16:52:30 INFO - PID 1971 | |10;stanford-crypto-aes;91;90;91;90;92;89;94;87;90;89 16:52:30 INFO - PID 1971 | |11;stanford-crypto-ccm;155;167;170;167;169;165;162;152;189;159 16:52:30 INFO - PID 1971 | |12;stanford-crypto-pbkdf2;202;204;204;208;201;199;201;203;200;196 16:52:30 INFO - PID 1971 | |13;stanford-crypto-sha256-iterative;73;74;74;76;75;73;72;71;75;72 16:52:30 INFO - PID 1971 | __end_tp_report 16:52:30 INFO - PID 1971 | __start_cc_report 16:52:30 INFO - PID 1971 | _x_x_mozilla_cycle_collect,2153 16:52:30 INFO - PID 1971 | __end_cc_report 16:52:30 INFO - PID 1971 | __startTimestamp1490831550660__endTimestamp 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | ------- Summary: start ------- 16:52:30 INFO - PID 1971 | Number of tests: 14 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | [#0] ai-astar Cycles:10 Average:136.40 Median:123.50 stddev:26.92 (21.8%) stddev-sans-first:26.93 16:52:30 INFO - PID 1971 | Values: 111.0 123.0 122.0 122.0 121.0 124.0 154.0 183.0 183.0 121.0 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | [#1] audio-beat-detection Cycles:10 Average:145.80 Median:150.00 stddev:27.61 (18.4%) stddev-sans-first:28.20 16:52:30 INFO - PID 1971 | Values: 167.0 149.0 105.0 200.0 113.0 146.0 157.0 151.0 148.0 122.0 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | [#2] audio-dft Cycles:10 Average:209.40 Median:209.50 stddev:2.84 (1.4%) stddev-sans-first:2.87 16:52:30 INFO - PID 1971 | Values: 207.0 209.0 214.0 210.0 208.0 214.0 209.0 208.0 205.0 210.0 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | [#3] audio-fft Cycles:10 Average:78.70 Median:79.00 stddev:13.90 (17.6%) stddev-sans-first:14.61 16:52:30 INFO - PID 1971 | Values: 84.0 64.0 63.0 102.0 68.0 76.0 91.0 93.0 82.0 64.0 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | [#4] audio-oscillator Cycles:10 Average:82.30 Median:83.00 stddev:9.45 (11.4%) stddev-sans-first:8.63 16:52:30 INFO - PID 1971 | Values: 96.0 74.0 96.0 74.0 76.0 76.0 90.0 76.0 75.0 90.0 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | [#5] imaging-gaussian-blur Cycles:10 Average:99.90 Median:93.50 stddev:15.10 (16.2%) stddev-sans-first:15.81 16:52:30 INFO - PID 1971 | Values: 93.0 94.0 94.0 93.0 115.0 138.0 95.0 95.0 92.0 90.0 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | [#6] imaging-darkroom Cycles:10 Average:108.20 Median:143.50 stddev:19.69 (13.7%) stddev-sans-first:20.46 16:52:30 INFO - PID 1971 | Values: 97.0 100.0 100.0 130.0 157.0 100.0 100.0 99.0 99.0 100.0 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | [#7] imaging-desaturate Cycles:10 Average:101.00 Median:102.00 stddev:1.41 (1.4%) stddev-sans-first:1.45 16:52:30 INFO - PID 1971 | Values: 100.0 102.0 98.0 102.0 101.0 100.0 101.0 103.0 102.0 101.0 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | [#8] json-parse-financial Cycles:10 Average:65.20 Median:65.00 stddev:0.79 (1.2%) stddev-sans-first:0.50 16:52:30 INFO - PID 1971 | Values: 67.0 65.0 65.0 64.0 65.0 65.0 65.0 65.0 65.0 66.0 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | [#9] json-stringify-tinderbox Cycles:10 Average:74.80 Median:64.00 stddev:18.26 (28.5%) stddev-sans-first:18.33 16:52:30 INFO - PID 1971 | Values: 58.0 87.0 104.0 108.0 72.0 64.0 63.0 63.0 65.0 64.0 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:90.50 stddev:1.89 (2.1%) stddev-sans-first:1.99 16:52:30 INFO - PID 1971 | Values: 91.0 90.0 91.0 90.0 92.0 89.0 94.0 87.0 90.0 89.0 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | [#11] stanford-crypto-ccm Cycles:10 Average:165.50 Median:167.00 stddev:10.20 (6.1%) stddev-sans-first:10.09 16:52:30 INFO - PID 1971 | Values: 155.0 167.0 170.0 167.0 169.0 165.0 162.0 152.0 189.0 159.0 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.80 Median:202.50 stddev:3.26 (1.6%) stddev-sans-first:3.46 16:52:30 INFO - PID 1971 | Values: 202.0 204.0 204.0 208.0 201.0 199.0 201.0 203.0 200.0 196.0 16:52:30 INFO - PID 1971 | 16:52:30 INFO - PID 1971 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:74.00 stddev:1.58 (2.1%) stddev-sans-first:1.67 16:52:30 INFO - PID 1971 | Values: 73.0 74.0 74.0 76.0 75.0 73.0 72.0 71.0 75.0 72.0 16:52:30 INFO - PID 1971 | -------- Summary: end -------- 16:52:30 INFO - PID 1971 | 16:52:31 INFO - TEST-INFO | 1971: exit 0 16:52:31 INFO - TEST-OK | kraken | took 63590ms 16:52:31 INFO - SUITE-END | took 1185s 16:52:31 INFO - Completed test suite (00:19:45) 16:52:31 INFO - javascript benchmark 16:52:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7640.121965650803, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3171.4132885416197, 3485.8082805445692, 3490.7084378779846, 3496.6328535503753, 3551.396262635352, 6034.215551978247, 6099.612447337997, 6211.751529938797, 6226.097215903697, 6230.5638605297645, 6138.909559458821, 6172.321044685081, 6227.221597300338, 6241.906371404421, 6258.561900666955, 5059.463916716152, 5385.703786291752, 5565.554755619561, 5572.888542229153, 5580.302462192226, 5104.412992505853, 5366.738816378148, 5669.518090962273, 5704.828855134353, 5718.428157184269, 4540.0, 5453.3728621208675, 5527.723613819293, 5539.639923404966, 5627.634203776742, 5519.862003449905, 5548.223248745179, 5551.611387202897, 5553.055980483327, 5554.611177217596, 5437.619366644337, 5502.174673798962, 5535.612507124503, 5544.91682624761, 5549.417311182348, 6145.170401995718, 6148.58582526017, 6175.567710260284, 6176.258848938111, 6176.907346389808, 6580.012998050331, 6652.833679158011, 6682.632455214961, 6693.698783554752, 6697.062411262427, 6681.832954176184, 6700.698468568878, 6758.1890173179045, 6763.830904227384, 6776.728930842761, 6146.555559443527, 6250.9062364064575, 6325.240971083499, 6331.271903731038, 6352.841178970562, 6725.630090345078, 6781.118454600969, 6889.827754306082, 6897.655117244118, 6903.930960690329, 6343.968280158662, 6422.3577642235405, 6424.871502569922, 6506.154199953976, 6530.510211734139, 6740.595564266062, 6747.460203183831, 6758.05387245786, 6758.155230596176, 6769.830754231184, 6622.96688516564, 6645.169353830771, 6649.434798042221, 6654.0, 6667.099941507931, 5363.5440987516495, 6476.514261430411, 6477.384648458389, 6484.1894763154605, 6667.899981500282, 5222.3733152022005, 5302.92045619316, 5316.043112239757, 5323.148296272655, 5323.920141197885, 4675.906481870412, 4714.57568818808, 4757.95242047582, 4800.66395352322, 4806.375171227718, 6147.846303842351, 6170.660613666291, 6236.750529978841, 6278.560500764994, 6319.58922670025, 6773.593584384953, 6778.356056174655, 6780.864382712417, 6783.423409010289, 6794.286599907037, 6287.622742635456, 6289.905651415233, 6303.527235457359, 6308.8422789429715, 6317.842053948688, 5355.410904800468, 5457.508824205841, 5762.942370576325, 5848.122781582797, 5961.254843144607, 6777.423918966943, 6789.558678685869, 6795.490338224752, 6798.0, 6799.150106236721, 6499.5775274607, 6576.8355791105605, 6578.473722102316, 6579.638119903451, 6601.207855057231, 6948.478864085113, 6954.582725036312, 6960.443164546723, 6964.373206411447, 6970.546914450544, 6560.343965603402, 6614.404703576702, 6616.437602803816, 6658.43403310724, 6674.799756007327, 6411.102445657705, 6520.739170433129, 6523.6738163092605, 6621.900671490028, 6741.1236539249585, 6496.155499784998, 6542.476601871934, 6558.8360290993105, 6567.44176744982, 6585.769498067544, 6533.085368048382, 6538.084668146368, 6546.214454265519, 6552.213734351908, 6556.147700798866, 6237.345078766664, 6296.307406185224, 6377.649229292433, 6413.326600706859, 6419.2296924369375, 6393.584417012879, 6457.4511166551365, 6501.08984742127, 6518.315576864361, 6526.0, 6371.076193951943, 6380.712867921047, 6404.743810247538, 6406.295307516077, 6409.583377080475], "value": 6095.978224063162, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.8812178173273, 815.1888870573778, 1123.0285802780593, 1143.371145869772, 1144.6852115668185, 36722.44916326257, 37487.06282342943, 37503.43744843829, 37535.436968445494, 37554.624453755525, 38848.611513884855, 39237.01907452315, 39258.60741392592, 39287.0, 39357.62248321309, 6030.155778191057, 8354.999999999985, 8496.107908669594, 8507.95746021269, 8517.53153576553, 6134.2332208473945, 6172.629642221458, 6206.7827626033095, 6209.658468784215, 8270.214329638704, 8493.36299777516, 8527.914720852774, 8529.232369086782, 8535.146485351446, 8585.570721463933, 8438.746837594881, 8501.617427215728, 8506.362022848249, 8510.48937063775, 8532.658693652247, 8330.791730206733, 8397.37019723517, 8402.78993025173, 8409.075001749863, 8444.451110677786, 37286.813565931996, 37517.24965500702, 37553.43669844954, 37615.62384376176, 37642.24715505674, 2487.5895477246413, 2926.2538052796613, 2990.5364668476204, 3001.83489908055, 3033.8331391773445, 26885.865570672217, 26928.326791830164, 26975.32561685954, 27081.593776093374, 27367.86316068427, 2756.421151558175, 2756.807023508355, 2757.8621068946672, 2781.902633407841, 2797.3286411261356, 294087.0, 294576.0, 295051.0, 295089.0, 295482.99999999785, 38791.41812872809, 39799.20401591952, 49142.26286605704, 49226.75386623044, 49328.0, 42191.15617687629, 49834.0, 49878.501214988115, 50564.74717626388, 50616.49383506191, 12241.816372754416, 13873.028887977745, 13985.440582376794, 14024.509142180179, 14054.71890562183, 9935.304528682922, 11093.944530277297, 11296.604618838466, 11299.604513841976, 11301.49143288554, 11839.17125801203, 11864.940675296568, 11885.762284754257, 11895.762084758257, 11968.281903085843, 5436.836894893159, 5439.646422982493, 5442.863928401822, 5461.9453805462235, 5478.205660179331, 965.7440778193644, 968.2157452463528, 971.7036303927229, 984.9704508864745, 991.8859331176862, 23874.88062559676, 23941.880290598434, 28452.71547284542, 28921.0, 29634.407311854073, 1045.2474218562622, 1046.1682962045193, 1084.3927400655657, 1084.6583326252205, 1096.7477480179443, 11673.649790506299, 12020.579279725167, 12051.276923384625, 15139.167345796088, 15267.23663816827, 376622.0, 377546.0, 378031.2196878051, 378106.0, 379194.0, 52495.73752131215, 52690.47309526932, 52704.736476317375, 52743.73628131835, 52874.471255287724, 1443.8411774704564, 1471.1026273972868, 1499.400239904069, 1503.7218114648824, 1516.5601975427223, 25823.354416139748, 26730.866345668146, 26881.059162929203, 27536.173914781786, 27579.586306205823, 36647.63352366442, 36726.44910326294, 36757.816210918776, 36771.44842827306, 36780.26439471249, 339325.0, 364082.0, 365320.0, 366580.0, 366584.0, 25298.620520692573, 30333.241668958453, 30558.777648893796, 30675.539866901567, 30759.538606920465, 38818.41772373473, 38907.805460972515, 39145.804270978464, 39153.608463915, 40047.19905601814, 27860.16419507418, 27877.0, 28795.568066479438, 28826.42347153087, 28868.27829304284, 23254.76745232526, 27551.31121721893, 27713.307167320978, 28239.435211295247, 28734.712652873204, 32887.506687400186, 32932.34135317328, 33064.0, 33073.33853322872, 35177.64822351744, 27232.863835680695, 29479.55780663249, 30380.0, 30488.39023219479, 30701.846490767402, 21859.344219673436, 22653.98057087402, 22809.657855131853, 23476.356655033982, 23858.0], "value": 16989.463791891056, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.4014647369813, 417.7660510114336, 429.15456550595337, 431.8941859244485, 445.594508996813, 15661.608459788511, 21473.892630536844, 21775.911204439795, 22242.88878555607, 22530.77469225307, 22018.256896327624, 22125.557488850212, 22219.333419997387, 22282.220122295723, 22362.776372236272, 5806.59353845231, 5896.852578685546, 5908.61593996391, 6018.759249630009, 6120.081987701836, 3576.5886923003795, 3778.338790711628, 5834.5040671542965, 5952.761889524414, 5957.165996760457, 3724.255148970217, 3755.830987605564, 3759.8684046058393, 3771.2268984858038, 3823.885283441501, 5955.255593050891, 5961.076033214858, 5975.282966044103, 5975.492083172936, 5987.850303742398, 5089.211172268304, 5234.6335756497065, 5294.761735721863, 5298.602604804617, 5303.390110137345, 25514.617280740804, 25562.488750224893, 25658.615120773204, 25739.742602573922, 25920.0, 1993.0931426201043, 1995.8702684325472, 2017.1326329678232, 2030.979690203094, 2082.9270975515783, 15095.698086038328, 15649.29578168985, 16169.595760105974, 16975.91512042444, 17583.73624395635, 1483.6216764725034, 1710.7604935309066, 1764.1532064608932, 1777.1380880273057, 1803.6933721267417, 142452.2877385617, 155001.0, 167568.32431675648, 169284.0, 171290.43064354046, 17369.91315043442, 23028.884855575616, 23340.64989025166, 23523.882380587987, 23728.0, 15500.0, 16061.598460038358, 16075.0, 41426.75719728413, 41633.79183104065, 8755.956220218859, 8856.468611883307, 8903.0, 8930.017698053209, 9029.367944243972, 8487.151284871463, 8518.70184543538, 8665.133486651284, 8666.78333041679, 8701.390902636882, 6995.615241161683, 7156.854903215564, 7528.623568821537, 7563.432258193389, 7710.884336734841, 2166.4150679316813, 2169.804717575426, 2173.3479956013134, 2176.2927048708916, 2300.2869110575775, 904.7153042679411, 906.5331354352513, 909.8089401225684, 941.5339406993583, 953.1612181280567, 16254.0, 17976.730349044774, 19401.932893690984, 20149.294774682814, 20604.484887877923, 877.9912200878038, 931.3713243560569, 953.1707414549386, 965.7440778193784, 972.5429048347265, 10069.748256293651, 10226.897731022595, 10279.743006424898, 10331.380117192992, 10363.378197308035, 685852.0, 689801.4278001083, 700078.0, 711587.0, 719820.0, 152142.47857521503, 152388.238058809, 153298.90361512103, 156915.0, 157317.0, 975.7658162041201, 1107.7618312062784, 1135.4266095621824, 1156.0693641618577, 1161.8838116188313, 17228.569285767957, 19736.506587335432, 20803.791962080184, 20839.374818755463, 20922.267720629705, 54784.630384240714, 57261.0, 59098.40901591101, 60423.39576604178, 60727.69636151791, 74450.62774686309, 76007.23992760223, 76010.61994689991, 76132.0, 76632.23367766474, 8165.183481651788, 8193.83612327762, 8526.531040792568, 8605.741827744927, 8626.051134375339, 12735.808962865749, 13125.934370328087, 13221.801672975105, 13279.468821247043, 13624.114432562248, 10630.681079567625, 11010.944945275221, 11397.259178153394, 11448.026917712088, 11614.825777613512, 10460.895391045991, 10655.89344106549, 10845.349279043283, 10884.782304354027, 10930.562777489129, 11599.246049006788, 11892.940535297268, 11979.401029948642, 11984.04127669767, 12064.155509114278, 8263.091059983517, 8387.748367548984, 8418.116097809641, 8475.406721529434, 8489.575521223791, 11047.613333533287, 11183.496742646437, 11495.252808567417, 11536.307821530734, 11624.476898539755], "value": 10323.70429345441, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1254.385351177923, 1364.6656569140562, 1417.7377185220735, 1439.596912864397, 1443.451488434395, 10640.893591064087, 11008.064314533265, 11074.446277686104, 11086.390248536327, 11088.667339979793, 10624.0, 10795.460226988658, 10980.945095274512, 11013.889861101386, 11020.834687479673, 2443.2303824295336, 2485.893777269116, 2487.465194983078, 2489.153687746166, 2493.5012997400504, 2408.277516744978, 2417.709874815016, 2473.6907886514186, 2474.8020158387376, 2476.727559968402, 2350.21267875262, 2457.58221102413, 2463.2610216934936, 2469.3085935937966, 2471.1845091119976, 2427.1141033522813, 2449.1428000199967, 2452.8037756979397, 2475.504899020203, 2489.9253022409357, 2436.171701621457, 2465.3097132802845, 2473.0849585653245, 2473.295110893405, 2475.207933461275, 10899.1280697544, 11125.0, 11125.777484450266, 11129.054030407426, 11147.108231341474, 6221.160143380676, 6461.0, 6469.061986012049, 6473.643949582771, 6477.417032467053, 3995.1210733638554, 4058.1072164123843, 4071.4503542021744, 4123.422720819088, 4153.522344930311, 1159.6231224851956, 1164.5381277123372, 1199.5741511763372, 1219.3415555600054, 1221.9450124744408, 14038.613435715954, 14486.20325882076, 15074.397024118913, 15299.617509562238, 15487.0, 4468.955310446919, 4532.478764942074, 5376.73116344189, 5402.108652072364, 5420.593455490854, 11497.425128743529, 11499.137564682682, 11512.424378781028, 11518.308901465938, 11521.308721476738, 5377.516023557899, 5591.41290164535, 6278.874422511615, 6300.432961033437, 6302.338254483305, 5739.368669446358, 5814.883702325929, 6023.036314189709, 6071.271447426248, 6084.5132389408745, 4178.498580170338, 4253.80857861397, 4275.700700950903, 4315.417418648505, 4335.501417336984, 1078.309881675728, 1244.4026867103842, 1247.1394737631006, 1260.6281147061457, 1261.4386597964, 765.1736124985114, 765.636322746702, 785.1912530094012, 793.6785601831267, 800.0918956983749, 5994.550408719363, 6064.454199122012, 6221.968890155611, 6263.498920086382, 6370.362963703686, 560.5963706131661, 569.4504802865255, 577.4398833131855, 580.5790801668757, 585.5666806563113, 4166.541680415152, 4263.680223983151, 4351.522684897452, 4400.75795831226, 4432.512423633398, 13674.453021879013, 14377.424903003972, 14390.712185756227, 14405.0, 14425.062370945854, 13814.171149731048, 14146.717065658835, 14297.857021429652, 14312.642183945276, 14316.568343165809, 10330.0, 10445.738856528647, 10449.0, 10451.16390688743, 10460.68617941463, 6381.106645069601, 6431.871362572816, 6530.118434011348, 6620.635865027277, 6629.535932484872, 12238.08214383907, 12606.117571769888, 12617.747645047231, 12626.179298345578, 12642.2414655121, 11927.76144477123, 12098.09264305163, 12373.443195056425, 12948.0, 12976.755811047238, 7993.92006079955, 8201.384896132695, 8415.116412776815, 8503.532305723245, 8511.319094472303, 10245.897541024495, 10374.066334029974, 10479.738006549896, 10491.108255798343, 10495.317804342692, 8761.0, 8766.167214114648, 8791.560421979004, 8852.601632926662, 8857.0, 8168.591570421337, 8416.15838416178, 8433.451825631315, 8442.409031367746, 8450.746477605682, 7796.9220307796195, 7870.094939082079, 7981.8802717958115, 7987.201279871966, 7990.680372785024, 5701.828945131652, 5989.52083833301, 6031.788887388921, 6181.041938499539, 6258.561900666909, 5835.766569337179, 6079.696015199134, 6167.568270221221, 6181.258249010148, 6214.067889816563], "value": 5141.443816755675, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.61294672960213, 387.5756047128394, 402.18557421222033, 405.42834603209445, 406.4289673009423, 5325.281087053247, 5499.147632117023, 6000.75996960121, 6318.715657795398, 6323.936760632387, 5552.890381386416, 5950.880982380349, 6019.488343490809, 6033.5474839387, 6164.6301221926715, 3639.8726044588443, 3912.921741565167, 4719.315699223611, 4776.474587795331, 4798.976005119969, 4191.643710284629, 4619.676622636417, 4621.722696638196, 4734.55021772931, 4828.589569886564, 4671.789769460383, 4701.7414042227665, 4731.834385796497, 4749.477557468693, 4755.6908800928, 3926.725129240954, 4716.151092803294, 4745.76271186441, 4752.312928910256, 4755.738434386108, 3755.6432138946757, 3801.6958643308744, 3938.916797880606, 3939.975606342343, 3955.9714474236616, 5726.541876649858, 5957.514853810526, 5993.392874099737, 6013.488853447462, 6030.0, 4019.658329042035, 5144.8616344030115, 5392.0, 5413.702246376448, 5416.972915135439, 6046.244219472566, 6180.474659653934, 6542.509311801634, 6583.473322134218, 6597.505187110986, 3875.9418608720894, 4373.693841431101, 4382.211201983641, 4385.890352741175, 4389.9025243689075, 5796.333421656479, 6528.281888992207, 6932.202796678396, 6938.1553563038915, 7032.789016329519, 4325.935110973338, 4411.117776444659, 4411.691181617321, 4423.024097889874, 4429.335599660012, 4474.217012022883, 4480.731156130578, 4495.483019452805, 4497.910041799146, 4499.752513611717, 4286.849960251377, 4477.820887164542, 4815.181419158752, 5350.38470575881, 5366.7048312343195, 6312.0, 6564.606123632598, 6602.372774586406, 6618.801435956929, 6667.499937504706, 1272.287518989369, 1405.8523854995078, 1469.8162729658793, 1476.3430273528177, 1477.6010477171103, 865.2342676731013, 879.9692010779592, 942.241495596038, 959.2949182351106, 966.4877614864133, 5312.5218730314455, 6162.784302549389, 6307.96846015767, 6331.176946996861, 6343.809685709436, 6554.442872355903, 6630.734770609218, 6750.662466876636, 6865.931340686529, 6888.035675005503, 5559.359304330492, 6220.298922141319, 6221.751129954841, 6256.186695729526, 6268.119564130834, 2859.8999035033676, 3121.406932682783, 3247.967520324814, 3565.3047655707405, 3572.821358932043, 7065.4074851346, 7070.505064645528, 7086.822829429306, 7108.3602475777425, 7121.857562848818, 6737.326267373322, 6879.552829066095, 6897.930820722796, 6966.9303306967295, 6979.953007048984, 5767.740451679684, 6692.99605059245, 6948.6178260195165, 6974.860502790018, 7018.298170183043, 5007.923296491272, 5602.579806514609, 6129.835331287041, 6678.599284042779, 6737.59574425536, 6698.363655452724, 6746.86506269862, 6756.560823546453, 6771.322867713189, 6823.727050917908, 6627.204735431779, 6729.69716362755, 6865.794026179223, 7006.719731210695, 7095.97108419266, 3654.196076863086, 4384.145091707086, 4393.20922234001, 4444.31113177458, 4444.422235103697, 5931.406859314034, 6575.50683698715, 6786.86213842638, 6836.111305530249, 6849.315068493111, 6049.818505444844, 6295.375385550648, 6468.579542329733, 6520.608763474207, 6558.213014438298, 5669.858253543694, 6040.063790112539, 6280.403361680633, 6417.021446703074, 6422.614643121427, 5253.317068781034, 6487.54587178893, 6488.837779055561, 6502.594040205155, 6515.315891831289, 6399.408239662944, 6547.461346583645, 6600.4127131643045, 6625.720268466946, 6634.009797060959, 5351.518363347317, 5717.771289148387, 5815.563832712648, 6310.085037669604, 6441.420272175526], "value": 4740.5227281612, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.4122598855942, 724.6666533394638, 735.6027745017689, 741.1106671993606, 766.2031487253257, 15735.449259275912, 16034.7594786078, 16282.755758663614, 16352.0, 16384.99999999997, 14535.49125780598, 15348.155851428168, 15458.768118478203, 15468.922655386736, 15480.458183963563, 945.7493764152505, 950.2540505703024, 956.1967946924582, 956.6508224498077, 956.7895063086145, 955.9426434413923, 956.93779904306, 961.9759506012335, 986.2356673577963, 1006.3961623026199, 1054.056619325703, 1056.8678915135608, 1057.6404022632303, 1058.2962330050507, 1058.5818601652343, 1052.1319911073394, 1052.1582733812995, 1052.6578861869923, 1053.1048608682597, 1053.5469748008354, 700.4431476975766, 784.9293563579248, 785.9214078592153, 786.0724345272577, 791.5409062743595, 16101.516954491266, 16535.751963720555, 16579.834201657948, 16689.49931502057, 16774.0, 14516.491922782652, 14662.0, 14676.0, 14697.0, 14743.0, 11697.94151029248, 11846.703832404173, 11902.58340958071, 12093.395330233541, 12345.69135771604, 2998.7900846940724, 3029.0618100442534, 3089.81461112334, 3127.8592463339205, 3155.0692545699076, 91415.08584914132, 112044.0, 120123.78638349775, 120247.0, 120471.99999999913, 9545.990624473543, 11396.316221026766, 11489.54041838317, 11511.827322590168, 11602.419879006016, 24683.01267949262, 24890.751092489205, 25138.748612513642, 25171.0, 25249.74750252474, 7803.013714991015, 7857.410694197958, 7916.406269529808, 7936.3254123399, 8067.596620168968, 4190.727602705814, 4196.181744559782, 4209.326507758805, 4226.535081141072, 4246.320588705792, 4108.4864391951005, 4181.581841815794, 4186.727862688916, 4220.134872351161, 4224.345226489899, 783.3693876429529, 783.6669415498391, 784.5606460382204, 792.84935862186, 798.6565776716068, 561.5535649158928, 563.5322682173786, 573.7131434282859, 582.4146732533776, 595.5531372888414, 9628.71113866585, 9854.950725246472, 10058.647947321808, 10102.69691909244, 10113.443760593238, 490.5437942713311, 543.0876128104821, 550.2736387967924, 552.7899546771661, 575.2579172866955, 4627.467841198305, 4729.976350118227, 4768.403949506312, 4768.732356632902, 4812.326274321597, 91437.54281228551, 95960.52019739996, 97728.0, 97990.10049497496, 98818.0, 73951.63024184844, 88250.0, 95280.37756979425, 96355.0, 97604.0, 80919.59540202378, 81559.5922020386, 82539.0, 82911.58544207361, 84334.15665843386, 8417.07412184647, 8872.467651940904, 9132.771680708036, 9421.38760980534, 9567.904320956979, 38988.61011389963, 40570.79714601408, 40906.0, 41284.38073428841, 41926.95182620459, 57117.85764284774, 60604.181874543836, 71770.0, 71855.0, 72555.27444725485, 13551.525696600966, 13601.115927464683, 13978.510752123628, 14138.152043762155, 14158.0, 12521.812172817234, 15023.248837558296, 15107.974370045986, 15209.695806084037, 15363.923180384027, 11713.472893719632, 13608.455661773418, 13697.931510342385, 13712.862871371159, 13765.518206862711, 11842.157643311895, 11971.401429928295, 13691.251066122057, 14020.929895350459, 14342.282885855875, 13037.60369579271, 13444.731105378034, 13633.659158521117, 13832.654183645489, 14258.358373872994, 9814.165795907427, 10056.597736090474, 10146.49267536635, 10237.436940968319, 10424.843627345444, 9380.906190938276, 9680.322377433793, 9939.721739302564, 11142.554297828321, 11206.775864482828], "value": 7631.343340406264, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1632.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 123.0, 122.0, 122.0, 121.0, 124.0, 154.0, 183.0, 183.0, 121.0], "value": 136.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 149.0, 105.0, 200.0, 113.0, 146.0, 157.0, 151.0, 148.0, 122.0], "value": 145.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 209.0, 214.0, 210.0, 208.0, 214.0, 209.0, 208.0, 205.0, 210.0], "value": 209.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 64.0, 63.0, 102.0, 68.0, 76.0, 91.0, 93.0, 82.0, 64.0], "value": 78.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 74.0, 96.0, 74.0, 76.0, 76.0, 90.0, 76.0, 75.0, 90.0], "value": 82.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 93.0, 115.0, 138.0, 95.0, 95.0, 92.0, 90.0], "value": 99.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 100.0, 100.0, 130.0, 157.0, 100.0, 100.0, 99.0, 99.0, 100.0], "value": 108.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 102.0, 98.0, 102.0, 101.0, 100.0, 101.0, 103.0, 102.0, 101.0], "value": 101.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 65.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0, 66.0], "value": 65.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 87.0, 104.0, 108.0, 72.0, 64.0, 63.0, 63.0, 65.0, 64.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 91.0, 90.0, 92.0, 89.0, 94.0, 87.0, 90.0, 89.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 167.0, 170.0, 167.0, 169.0, 165.0, 162.0, 152.0, 189.0, 159.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 204.0, 204.0, 208.0, 201.0, 199.0, 201.0, 203.0, 200.0, 196.0], "value": 201.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 76.0, 75.0, 73.0, 72.0, 71.0, 75.0, 72.0], "value": 73.5, "unit": "score"}]}]} 16:52:31 INFO - Return code: 0 16:52:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:52:31 ERROR - # TBPL SUCCESS # 16:52:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:52:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:52:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:52:31 INFO - 2017-03-29 16:52:31.996 screenresolution[1975:20006] starting screenresolution argv=screenresolution get 16:52:31 INFO - 2017-03-29 16:52:31.998 screenresolution[1975:20006] Display 0: 1600x1200x32@60 16:52:32 INFO - 2017-03-29 16:52:32.005 screenresolution[1976:20009] starting screenresolution argv=screenresolution list 16:52:32 INFO - Available Modes on Display 0 16:52:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:52:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:52:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:52:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:52:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:52:32 INFO - Intel Iris: 16:52:32 INFO - Chipset Model: Intel Iris 16:52:32 INFO - Type: GPU 16:52:32 INFO - Bus: Built-In 16:52:32 INFO - VRAM (Dynamic, Max): 1536 MB 16:52:32 INFO - Vendor: Intel (0x8086) 16:52:32 INFO - Device ID: 0x0a2e 16:52:32 INFO - Revision ID: 0x0009 16:52:32 INFO - Displays: 16:52:32 INFO - Display: 16:52:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:52:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:52:32 INFO - Display Serial Number: 1600x1200 60 16:52:32 INFO - Main Display: Yes 16:52:32 INFO - Mirror: Off 16:52:32 INFO - Online: Yes 16:52:32 INFO - Rotation: Supported 16:52:32 INFO - Return code: 0 16:52:32 INFO - Running post-action listener: _resource_record_post_action 16:52:32 INFO - [mozharness: 2017-03-29 23:52:32.241358Z] Finished run-tests step (success) 16:52:32 INFO - Running post-run listener: _resource_record_post_run 16:52:32 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 921600; Write bytes: 510428672; Read time: 8; Write time: 1559 16:52:32 INFO - TinderboxPrint: CPU usage
53.3% 16:52:32 INFO - TinderboxPrint: I/O read bytes / time
921,600 / 8 16:52:32 INFO - TinderboxPrint: I/O write bytes / time
510,428,672 / 1,559 16:52:32 INFO - TinderboxPrint: CPU idle
2,253.6 (46.7%) 16:52:32 INFO - TinderboxPrint: CPU system
957.2 (19.8%) 16:52:32 INFO - TinderboxPrint: CPU user
1,614.9 (33.5%) 16:52:32 INFO - TinderboxPrint: Swap in / out
211,775,488 / 0 16:52:32 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204868096; Write bytes: 205092352; Read time: 15694; Write time: 455 16:52:32 INFO - run-tests - Wall time: 1187s; CPU: 53.0%; Read bytes: 921600; Write bytes: 295370752; Read time: 8; Write time: 1072 16:52:32 INFO - Running post-run listener: _upload_blobber_files 16:52:32 INFO - Blob upload gear active. 16:52:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:52:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:52:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:52:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:52:33 INFO - (blobuploader) - INFO - Open directory for files ... 16:52:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:52:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:52:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:34 INFO - (blobuploader) - INFO - Done attempting. 16:52:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:52:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:52:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:35 INFO - (blobuploader) - INFO - Done attempting. 16:52:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:52:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:52:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:36 INFO - (blobuploader) - INFO - Done attempting. 16:52:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:52:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:52:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:38 INFO - (blobuploader) - INFO - Done attempting. 16:52:38 INFO - (blobuploader) - INFO - Iteration through files over. 16:52:38 INFO - Return code: 0 16:52:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:52:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:52:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf36637e84ced6ae67c75d47dad1fd6348d9a6bd668eb980cee5805f08f14854ee47017371b28e4149a7bfc997a481bfd9f94ded67c9ed43f685eb308fa2713d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff09740ce9b88a8ef54a3767249f94ab09505783fb8644505b0399d2fa2b79a3bd05c7df50b16df9a7a70a8730a2435eb342d4dd4b37ac84e3440a05e1f5b888", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9ad7c323869e2659ce188b76464e019a6e806198649e84623ccf54027b232ecc98f3d148c1a6f799e2babd303b4ada4f7a13399c2a6cca860f22307207661e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2117afb223171004515159bd685676fa0fae52a82ff6090907f3f360f8cfd7a2e35018d77e1bdea5e9df44d4987490b55494395f040a966f408221c13d831697"} 16:52:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:52:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:52:38 INFO - Contents: 16:52:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf36637e84ced6ae67c75d47dad1fd6348d9a6bd668eb980cee5805f08f14854ee47017371b28e4149a7bfc997a481bfd9f94ded67c9ed43f685eb308fa2713d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff09740ce9b88a8ef54a3767249f94ab09505783fb8644505b0399d2fa2b79a3bd05c7df50b16df9a7a70a8730a2435eb342d4dd4b37ac84e3440a05e1f5b888", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9ad7c323869e2659ce188b76464e019a6e806198649e84623ccf54027b232ecc98f3d148c1a6f799e2babd303b4ada4f7a13399c2a6cca860f22307207661e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2117afb223171004515159bd685676fa0fae52a82ff6090907f3f360f8cfd7a2e35018d77e1bdea5e9df44d4987490b55494395f040a966f408221c13d831697"} 16:52:38 INFO - Running post-run listener: copy_logs_to_upload_dir 16:52:38 INFO - Copying logs to upload dir... 16:52:38 INFO - mkdir: /builds/slave/test/build/upload/logs 16:52:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.746633 ========= master_lag: 1.90 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-03-29 16:52:40.341661) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 16:52:40.345014) ========= 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.TjUNK30j7g/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.DnKeNVZ9qC/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf36637e84ced6ae67c75d47dad1fd6348d9a6bd668eb980cee5805f08f14854ee47017371b28e4149a7bfc997a481bfd9f94ded67c9ed43f685eb308fa2713d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff09740ce9b88a8ef54a3767249f94ab09505783fb8644505b0399d2fa2b79a3bd05c7df50b16df9a7a70a8730a2435eb342d4dd4b37ac84e3440a05e1f5b888", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9ad7c323869e2659ce188b76464e019a6e806198649e84623ccf54027b232ecc98f3d148c1a6f799e2babd303b4ada4f7a13399c2a6cca860f22307207661e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2117afb223171004515159bd685676fa0fae52a82ff6090907f3f360f8cfd7a2e35018d77e1bdea5e9df44d4987490b55494395f040a966f408221c13d831697"} build_url:https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011145 build_url: 'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf36637e84ced6ae67c75d47dad1fd6348d9a6bd668eb980cee5805f08f14854ee47017371b28e4149a7bfc997a481bfd9f94ded67c9ed43f685eb308fa2713d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff09740ce9b88a8ef54a3767249f94ab09505783fb8644505b0399d2fa2b79a3bd05c7df50b16df9a7a70a8730a2435eb342d4dd4b37ac84e3440a05e1f5b888", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9ad7c323869e2659ce188b76464e019a6e806198649e84623ccf54027b232ecc98f3d148c1a6f799e2babd303b4ada4f7a13399c2a6cca860f22307207661e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2117afb223171004515159bd685676fa0fae52a82ff6090907f3f360f8cfd7a2e35018d77e1bdea5e9df44d4987490b55494395f040a966f408221c13d831697"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 16:52:40.384335) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:52:40.384659) ========= 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.TjUNK30j7g/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.DnKeNVZ9qC/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.005589 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:52:40.434489) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 16:52:40.434966) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 16:52:40.435415) ========= ========= Total master_lag: 2.05 =========