builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0284 starttime: 1495822956.97 results: success (0) buildid: 20170526100115 builduid: d761749dd2564b149b73c492c7d8c34d revision: 9baa8e24cc9cba93a1fbc1fc3a31ce19ed7e2766 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 11:22:36.969041) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 11:22:36.969507) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 11:22:36.969788) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 11:22:37.001087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 11:22:37.001519) ========= 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.x7H4PguVn5/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.HQCPL0jPez/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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-05-26 11:22:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.5M=0.001s 2017-05-26 11:22:37 (18.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.112711 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 11:22:37.140830) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 11:22:37.141240) ========= 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.x7H4PguVn5/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.HQCPL0jPez/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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.028342 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 11:22:37.190237) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-26 11:22:37.193790) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9baa8e24cc9cba93a1fbc1fc3a31ce19ed7e2766 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9baa8e24cc9cba93a1fbc1fc3a31ce19ed7e2766 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x7H4PguVn5/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.HQCPL0jPez/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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-05-26 11:22:37,292 truncating revision to first 12 chars 2017-05-26 11:22:37,292 Setting DEBUG logging. 2017-05-26 11:22:37,292 attempt 1/10 2017-05-26 11:22:37,292 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9baa8e24cc9c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-26 11:22:38,305 unpacking tar archive at: mozilla-beta-9baa8e24cc9c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.295205 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-26 11:22:38.511074) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 11:22:38.511440) ========= script_repo_revision: 9baa8e24cc9cba93a1fbc1fc3a31ce19ed7e2766 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 11:22:38.511965) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 11:22:38.512255) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 11:22:38.531674) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-26 11:22:38.532089) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x7H4PguVn5/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.HQCPL0jPez/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 11:22:38 INFO - MultiFileLogger online at 20170526 11:22:38 in /builds/slave/test 11:22:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 11:22:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:22:38 INFO - {'append_to_log': False, 11:22:38 INFO - 'base_work_dir': '/builds/slave/test', 11:22:38 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:22:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:22:38 INFO - 'branch': 'Mozilla-Beta', 11:22:38 INFO - 'buildbot_json_path': 'buildprops.json', 11:22:38 INFO - 'config_files': ('talos/mac_config.py',), 11:22:38 INFO - 'default_actions': ('clobber', 11:22:38 INFO - 'read-buildbot-config', 11:22:38 INFO - 'download-and-extract', 11:22:38 INFO - 'populate-webroot', 11:22:38 INFO - 'create-virtualenv', 11:22:38 INFO - 'install', 11:22:38 INFO - 'run-tests'), 11:22:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:22:38 INFO - 'download_minidump_stackwalk': True, 11:22:38 INFO - 'download_symbols': 'ondemand', 11:22:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:22:38 INFO - 'tooltool.py': '/tools/tooltool.py', 11:22:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:22:38 INFO - '/tools/misc-python/virtualenv.py')}, 11:22:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:22:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:22:38 INFO - 'gecko_profile': False, 11:22:38 INFO - 'gecko_profile_interval': 0, 11:22:38 INFO - 'installer_path': 'installer.exe', 11:22:38 INFO - 'log_level': 'info', 11:22:38 INFO - 'log_name': 'talos', 11:22:38 INFO - 'log_to_console': True, 11:22:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:22:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:22:38 INFO - 'opt_config_files': (), 11:22:38 INFO - 'pip_index': False, 11:22:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:22:38 INFO - 'cmd': ('bash', 11:22:38 INFO - '-c', 11:22:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:22:38 INFO - 'enabled': True, 11:22:38 INFO - 'halt_on_failure': False, 11:22:38 INFO - 'name': 'check_screen_resolution'},), 11:22:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:22:38 INFO - 'cmd': ('bash', 11:22:38 INFO - '-c', 11:22:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:22:38 INFO - 'enabled': True, 11:22:38 INFO - 'halt_on_failure': False, 11:22:38 INFO - 'name': 'check_screen_resolution'},), 11:22:38 INFO - 'run_cmd_checks_enabled': True, 11:22:38 INFO - 'suite': 'dromaeojs', 11:22:38 INFO - 'system_bits': '32', 11:22:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:22:38 INFO - 'title': 't-yosemite-r7-0284', 11:22:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:22:38 INFO - 'use_talos_json': True, 11:22:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:22:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:22:38 INFO - 'work_dir': 'build'} 11:22:38 INFO - [mozharness: 2017-05-26 18:22:38.735867Z] Running clobber step. 11:22:38 INFO - Running pre-action listener: _resource_record_pre_action 11:22:38 INFO - Running main action method: clobber 11:22:38 INFO - rmtree: /builds/slave/test/build 11:22:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:22:41 INFO - Running post-action listener: _resource_record_post_action 11:22:41 INFO - [mozharness: 2017-05-26 18:22:41.205369Z] Finished clobber step (success) 11:22:41 INFO - [mozharness: 2017-05-26 18:22:41.205501Z] Running read-buildbot-config step. 11:22:41 INFO - Running pre-action listener: _resource_record_pre_action 11:22:41 INFO - Running main action method: read_buildbot_config 11:22:41 INFO - Using buildbot properties: 11:22:41 INFO - { 11:22:41 INFO - "project": "", 11:22:41 INFO - "product": "firefox", 11:22:41 INFO - "script_repo_revision": "production", 11:22:41 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 11:22:41 INFO - "repository": "", 11:22:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 11:22:41 INFO - "buildid": "20170526100115", 11:22:41 INFO - "pgo_build": "False", 11:22:41 INFO - "basedir": "/builds/slave/test", 11:22:41 INFO - "buildnumber": 4, 11:22:41 INFO - "slavename": "t-yosemite-r7-0284", 11:22:41 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 11:22:41 INFO - "platform": "yosemite_r7_devedition", 11:22:41 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 11:22:41 INFO - "branch": "mozilla-beta", 11:22:41 INFO - "repo_path": "releases/mozilla-beta", 11:22:41 INFO - "revision": "9baa8e24cc9cba93a1fbc1fc3a31ce19ed7e2766", 11:22:41 INFO - "stage_platform": "macosx64-devedition", 11:22:41 INFO - "builduid": "d761749dd2564b149b73c492c7d8c34d", 11:22:41 INFO - "slavebuilddir": "test" 11:22:41 INFO - } 11:22:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 11:22:41 INFO - Running post-action listener: _resource_record_post_action 11:22:41 INFO - [mozharness: 2017-05-26 18:22:41.208737Z] Finished read-buildbot-config step (success) 11:22:41 INFO - [mozharness: 2017-05-26 18:22:41.208823Z] Running download-and-extract step. 11:22:41 INFO - Running pre-action listener: _resource_record_pre_action 11:22:41 INFO - Running main action method: download_and_extract 11:22:41 INFO - mkdir: /builds/slave/test/build/tests 11:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:22:41 INFO - trying https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 11:22:41 INFO - Downloading https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 11:22:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 11:22:42 INFO - Downloaded 1287 bytes. 11:22:42 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 11:22:42 INFO - Using the following test package requirements: 11:22:42 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:22:42 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 11:22:42 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:22:42 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:22:42 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 11:22:42 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:22:42 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 11:22:42 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 11:22:42 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:22:42 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 11:22:42 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:22:42 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:22:42 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 11:22:42 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:22:42 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 11:22:42 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:22:42 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 11:22:42 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:22:42 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 11:22:42 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 11:22:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:22:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:22:42 INFO - Fetch https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:22:43 INFO - Expected file size: 51965499 11:22:43 INFO - Obtained file size: 51965499 11:23:00 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 11:23:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:23:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:23:00 INFO - Fetch https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:23:02 INFO - Expected file size: 51965499 11:23:02 INFO - Obtained file size: 51965499 11:23:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 11:23:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 11:23:17 INFO - Fetch https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 11:23:18 INFO - Expected file size: 14404449 11:23:18 INFO - Obtained file size: 14404449 11:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:18 INFO - trying https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:23:18 INFO - Downloading https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 11:23:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 11:23:20 INFO - Downloaded 74891431 bytes. 11:23:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:23:20 INFO - mkdir: /builds/slave/test/properties 11:23:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:23:20 INFO - Writing to file /builds/slave/test/properties/build_url 11:23:20 INFO - Contents: 11:23:20 INFO - build_url:https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:23:21 INFO - Running post-action listener: _resource_record_post_action 11:23:21 INFO - Running post-action listener: set_extra_try_arguments 11:23:21 INFO - [mozharness: 2017-05-26 18:23:21.350396Z] Finished download-and-extract step (success) 11:23:21 INFO - [mozharness: 2017-05-26 18:23:21.350568Z] Running populate-webroot step. 11:23:21 INFO - Running pre-action listener: _resource_record_pre_action 11:23:21 INFO - Running main action method: populate_webroot 11:23:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:23:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:23:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:23:21 INFO - '--tppagecycles', 11:23:21 INFO - '7'], 11:23:21 INFO - 'tests': ['tsvgm']}}, 11:23:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:23:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:23:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:23:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:23:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:23:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:23:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:23:21 INFO - 'talos_options': ['--disable-e10s'], 11:23:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:23:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:23:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:23:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:23:21 INFO - 'talos_options': ['--disable-e10s'], 11:23:21 INFO - 'tests': ['damp', 'tps']}, 11:23:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:23:21 INFO - 'tests': ['damp', 'tps']}, 11:23:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:23:21 INFO - 'tests': ['dromaeo_dom']}, 11:23:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:23:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:23:21 INFO - 'tests': ['basic_compositor_video']}, 11:23:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:23:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:23:21 INFO - 'tests': ['a11yr', 11:23:21 INFO - 'ts_paint', 11:23:21 INFO - 'tpaint', 11:23:21 INFO - 'sessionrestore', 11:23:21 INFO - 'sessionrestore_no_auto_restore', 11:23:21 INFO - 'tabpaint']}, 11:23:21 INFO - 'other-e10s': {'tests': ['a11yr', 11:23:21 INFO - 'ts_paint', 11:23:21 INFO - 'tpaint', 11:23:21 INFO - 'sessionrestore', 11:23:21 INFO - 'sessionrestore_no_auto_restore', 11:23:21 INFO - 'tabpaint']}, 11:23:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:23:21 INFO - 'ts_paint', 11:23:21 INFO - 'tpaint', 11:23:21 INFO - 'sessionrestore', 11:23:21 INFO - 'sessionrestore_no_auto_restore', 11:23:21 INFO - 'tabpaint']}, 11:23:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:23:21 INFO - 'ts_paint', 11:23:21 INFO - 'tpaint', 11:23:21 INFO - 'sessionrestore', 11:23:21 INFO - 'sessionrestore_no_auto_restore', 11:23:21 INFO - 'tabpaint']}, 11:23:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:23:21 INFO - 'tests': ['a11yr', 11:23:21 INFO - 'ts_paint', 11:23:21 INFO - 'tpaint', 11:23:21 INFO - 'sessionrestore', 11:23:21 INFO - 'sessionrestore_no_auto_restore', 11:23:21 INFO - 'tabpaint']}, 11:23:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:23:21 INFO - 'tests': ['a11yr', 11:23:21 INFO - 'ts_paint', 11:23:21 INFO - 'tpaint', 11:23:21 INFO - 'sessionrestore', 11:23:21 INFO - 'sessionrestore_no_auto_restore', 11:23:21 INFO - 'tabpaint']}, 11:23:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:23:21 INFO - 'tests': ['tsvgx', 11:23:21 INFO - 'tsvgr_opacity', 11:23:21 INFO - 'tart', 11:23:21 INFO - 'tscrollx', 11:23:21 INFO - 'cart', 11:23:21 INFO - 'tsvg_static']}, 11:23:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:23:21 INFO - 'tsvgr_opacity', 11:23:21 INFO - 'tart', 11:23:21 INFO - 'tscrollx', 11:23:21 INFO - 'cart', 11:23:21 INFO - 'tsvg_static']}, 11:23:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:23:21 INFO - 'talos_options': ['--disable-e10s'], 11:23:21 INFO - 'tests': ['tp5o']}, 11:23:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:23:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:23:21 INFO - 'talos_options': ['--disable-e10s', 11:23:21 INFO - '--xperf_path', 11:23:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:23:21 INFO - 'tests': ['tp5n']}, 11:23:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:23:21 INFO - 'talos_options': ['--xperf_path', 11:23:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:23:21 INFO - 'tests': ['tp5n']}}, 11:23:21 INFO - 'talos.zip': {'path': '', 11:23:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:23:21 INFO - Running post-action listener: _resource_record_post_action 11:23:21 INFO - [mozharness: 2017-05-26 18:23:21.368980Z] Finished populate-webroot step (success) 11:23:21 INFO - [mozharness: 2017-05-26 18:23:21.369115Z] Running create-virtualenv step. 11:23:21 INFO - Running pre-action listener: _resource_record_pre_action 11:23:21 INFO - Running main action method: create_virtualenv 11:23:21 INFO - Creating virtualenv /builds/slave/test/build/venv 11:23:21 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 11:23:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:23:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:23:21 INFO - Using real prefix '/tools/python27' 11:23:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:23:22 INFO - Installing distribute.............................................................................................................................................................................................done. 11:23:25 INFO - Installing pip.................done. 11:23:25 INFO - Return code: 0 11:23:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:23:25 INFO - Reading from file tmpfile_stdout 11:23:25 INFO - Output received: 11:23:25 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) 11:23:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:25 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/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 0x10b9bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90e1492b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79ad30>, '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 0x10b6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b78fbe8>, '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.x7H4PguVn5/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.HQCPL0jPez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 11:23:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 11:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:23:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x7H4PguVn5/Render', 11:23:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:25 INFO - 'HOME': '/Users/cltbld', 11:23:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:25 INFO - 'LOGNAME': 'cltbld', 11:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:25 INFO - 'MOZ_NO_REMOTE': '1', 11:23:25 INFO - 'NO_EM_RESTART': '1', 11:23:25 INFO - 'PAGER': '/bin/cat', 11:23:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:25 INFO - 'PWD': '/builds/slave/test', 11:23:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:25 INFO - 'SHELL': '/bin/bash', 11:23:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HQCPL0jPez/Listeners', 11:23:25 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 11:23:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:25 INFO - 'USER': 'cltbld', 11:23:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:25 INFO - 'XPC_FLAGS': '0x0', 11:23:25 INFO - 'XPC_SERVICE_NAME': '0', 11:23:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:25 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 11:23:25 INFO - Cleaning up... 11:23:25 INFO - Return code: 0 11:23:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:25 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/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 0x10b9bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90e1492b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79ad30>, '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 0x10b6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b78fbe8>, '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.x7H4PguVn5/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.HQCPL0jPez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 11:23:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 11:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:23:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x7H4PguVn5/Render', 11:23:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:25 INFO - 'HOME': '/Users/cltbld', 11:23:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:25 INFO - 'LOGNAME': 'cltbld', 11:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:25 INFO - 'MOZ_NO_REMOTE': '1', 11:23:25 INFO - 'NO_EM_RESTART': '1', 11:23:25 INFO - 'PAGER': '/bin/cat', 11:23:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:25 INFO - 'PWD': '/builds/slave/test', 11:23:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:25 INFO - 'SHELL': '/bin/bash', 11:23:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HQCPL0jPez/Listeners', 11:23:25 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 11:23:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:25 INFO - 'USER': 'cltbld', 11:23:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:25 INFO - 'XPC_FLAGS': '0x0', 11:23:25 INFO - 'XPC_SERVICE_NAME': '0', 11:23:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:26 INFO - Downloading/unpacking psutil>=3.1.1 11:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:23:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:23:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:23:27 INFO - Installing collected packages: psutil 11:23:27 INFO - Running setup.py install for psutil 11:23:28 INFO - building 'psutil._psutil_osx' extension 11:23:28 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 11:23:28 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 11:23:28 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 11:23:28 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 11:23:28 INFO - building 'psutil._psutil_posix' extension 11:23:28 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 11:23:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:23:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:23:28 INFO - ^ 11:23:28 INFO - 1 warning generated. 11:23:28 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 11:23:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:23:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:23:29 INFO - Successfully installed psutil 11:23:29 INFO - Cleaning up... 11:23:29 INFO - Return code: 0 11:23:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10b9bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90e1492b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79ad30>, '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 0x10b6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b78fbe8>, '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.x7H4PguVn5/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.HQCPL0jPez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 11:23:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 11:23:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x7H4PguVn5/Render', 11:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:29 INFO - 'HOME': '/Users/cltbld', 11:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:29 INFO - 'LOGNAME': 'cltbld', 11:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:29 INFO - 'MOZ_NO_REMOTE': '1', 11:23:29 INFO - 'NO_EM_RESTART': '1', 11:23:29 INFO - 'PAGER': '/bin/cat', 11:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:29 INFO - 'PWD': '/builds/slave/test', 11:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:29 INFO - 'SHELL': '/bin/bash', 11:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HQCPL0jPez/Listeners', 11:23:29 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 11:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:29 INFO - 'USER': 'cltbld', 11:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:29 INFO - 'XPC_FLAGS': '0x0', 11:23:29 INFO - 'XPC_SERVICE_NAME': '0', 11:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:23:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:23:30 INFO - Installing collected packages: mozsystemmonitor 11:23:30 INFO - Running setup.py install for mozsystemmonitor 11:23:31 INFO - Successfully installed mozsystemmonitor 11:23:31 INFO - Cleaning up... 11:23:31 INFO - Return code: 0 11:23:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:31 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/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 0x10b9bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90e1492b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79ad30>, '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 0x10b6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b78fbe8>, '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.x7H4PguVn5/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.HQCPL0jPez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 11:23:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 11:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:23:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x7H4PguVn5/Render', 11:23:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:31 INFO - 'HOME': '/Users/cltbld', 11:23:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:31 INFO - 'LOGNAME': 'cltbld', 11:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:31 INFO - 'MOZ_NO_REMOTE': '1', 11:23:31 INFO - 'NO_EM_RESTART': '1', 11:23:31 INFO - 'PAGER': '/bin/cat', 11:23:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:31 INFO - 'PWD': '/builds/slave/test', 11:23:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:31 INFO - 'SHELL': '/bin/bash', 11:23:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HQCPL0jPez/Listeners', 11:23:31 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 11:23:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:31 INFO - 'USER': 'cltbld', 11:23:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:31 INFO - 'XPC_FLAGS': '0x0', 11:23:31 INFO - 'XPC_SERVICE_NAME': '0', 11:23:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:31 INFO - Downloading/unpacking jsonschema==2.5.1 11:23:31 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 11:23:31 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 11:23:31 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 11:23:31 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 11:23:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:23:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:23:32 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 11:23:32 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 11:23:32 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 11:23:32 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 11:23:33 INFO - Downloading functools32-3.2.3-2.tar.gz 11:23:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:23:33 INFO - warning: no files found matching '*.txt' 11:23:33 INFO - no previously-included directories found matching 'build' 11:23:33 INFO - no previously-included directories found matching 'dist' 11:23:33 INFO - no previously-included directories found matching '.git*' 11:23:33 INFO - Installing collected packages: jsonschema, functools32 11:23:33 INFO - Running setup.py install for functools32 11:23:33 INFO - warning: no files found matching '*.txt' 11:23:33 INFO - no previously-included directories found matching 'build' 11:23:33 INFO - no previously-included directories found matching 'dist' 11:23:33 INFO - no previously-included directories found matching '.git*' 11:23:33 INFO - Successfully installed jsonschema functools32 11:23:33 INFO - Cleaning up... 11:23:33 INFO - Return code: 0 11:23:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:23:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:34 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/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 0x10b9bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90e1492b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79ad30>, '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 0x10b6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b78fbe8>, '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.x7H4PguVn5/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.HQCPL0jPez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 11:23:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 11:23:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x7H4PguVn5/Render', 11:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:34 INFO - 'HOME': '/Users/cltbld', 11:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:34 INFO - 'LOGNAME': 'cltbld', 11:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:34 INFO - 'MOZ_NO_REMOTE': '1', 11:23:34 INFO - 'NO_EM_RESTART': '1', 11:23:34 INFO - 'PAGER': '/bin/cat', 11:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:34 INFO - 'PWD': '/builds/slave/test', 11:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:34 INFO - 'SHELL': '/bin/bash', 11:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HQCPL0jPez/Listeners', 11:23:34 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 11:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:34 INFO - 'USER': 'cltbld', 11:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:34 INFO - 'XPC_FLAGS': '0x0', 11:23:34 INFO - 'XPC_SERVICE_NAME': '0', 11:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:23:34 INFO - Cleaning up... 11:23:34 INFO - Return code: 0 11:23:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:34 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/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 0x10b9bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90e1492b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79ad30>, '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 0x10b6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b78fbe8>, '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.x7H4PguVn5/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.HQCPL0jPez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 11:23:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 11:23:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x7H4PguVn5/Render', 11:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:34 INFO - 'HOME': '/Users/cltbld', 11:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:34 INFO - 'LOGNAME': 'cltbld', 11:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:34 INFO - 'MOZ_NO_REMOTE': '1', 11:23:34 INFO - 'NO_EM_RESTART': '1', 11:23:34 INFO - 'PAGER': '/bin/cat', 11:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:34 INFO - 'PWD': '/builds/slave/test', 11:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:34 INFO - 'SHELL': '/bin/bash', 11:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HQCPL0jPez/Listeners', 11:23:34 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 11:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:34 INFO - 'USER': 'cltbld', 11:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:34 INFO - 'XPC_FLAGS': '0x0', 11:23:34 INFO - 'XPC_SERVICE_NAME': '0', 11:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:35 INFO - Downloading/unpacking blobuploader==1.2.4 11:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:36 INFO - Downloading blobuploader-1.2.4.tar.gz 11:23:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:23:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:23:36 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 11:23:36 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 11:23:36 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 11:23:36 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 11:23:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:23:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:23:37 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 11:23:37 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 11:23:37 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 11:23:37 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 11:23:37 INFO - Downloading docopt-0.6.1.tar.gz 11:23:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:23:37 INFO - Installing collected packages: blobuploader, requests, docopt 11:23:37 INFO - Running setup.py install for blobuploader 11:23:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:23:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:23:37 INFO - Running setup.py install for requests 11:23:38 INFO - Running setup.py install for docopt 11:23:38 INFO - Successfully installed blobuploader requests docopt 11:23:38 INFO - Cleaning up... 11:23:38 INFO - Return code: 0 11:23:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:23:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:38 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/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 0x10b9bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90e1492b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79ad30>, '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 0x10b6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b78fbe8>, '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.x7H4PguVn5/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.HQCPL0jPez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 11:23:38 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:23:38 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:23:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x7H4PguVn5/Render', 11:23:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:38 INFO - 'HOME': '/Users/cltbld', 11:23:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:38 INFO - 'LOGNAME': 'cltbld', 11:23:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:38 INFO - 'MOZ_NO_REMOTE': '1', 11:23:38 INFO - 'NO_EM_RESTART': '1', 11:23:38 INFO - 'PAGER': '/bin/cat', 11:23:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:38 INFO - 'PWD': '/builds/slave/test', 11:23:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:38 INFO - 'SHELL': '/bin/bash', 11:23:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HQCPL0jPez/Listeners', 11:23:38 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 11:23:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:38 INFO - 'USER': 'cltbld', 11:23:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:38 INFO - 'XPC_FLAGS': '0x0', 11:23:38 INFO - 'XPC_SERVICE_NAME': '0', 11:23:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:23:38 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-7SW24q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:23:38 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-DoTgue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:23:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-pNl6Y4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:23:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-4PGCE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:23:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-pZYgiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:23:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-86sQpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:23:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-iqEM0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:23:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-7uArHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:23:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-OOEqUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:23:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-oeRV_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:23:39 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-P_p8vH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:23:40 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-pHjK42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:23:40 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-oUUWHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:23:40 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-uR7AUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:40 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-bGRfLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:23:40 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-Zr61K4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:23:40 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-X9cJIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:23:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:23:40 INFO - Running setup.py install for manifestparser 11:23:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:23:40 INFO - Running setup.py install for mozcrash 11:23:40 INFO - Running setup.py install for mozdebug 11:23:41 INFO - Running setup.py install for mozdevice 11:23:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:23:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:23:41 INFO - Running setup.py install for mozfile 11:23:41 INFO - Running setup.py install for mozhttpd 11:23:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:23:41 INFO - Running setup.py install for mozinfo 11:23:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:23:41 INFO - Running setup.py install for mozInstall 11:23:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:23:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:23:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:23:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:23:41 INFO - Running setup.py install for mozleak 11:23:41 INFO - Running setup.py install for mozlog 11:23:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:23:42 INFO - Running setup.py install for moznetwork 11:23:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:23:42 INFO - Running setup.py install for mozprocess 11:23:42 INFO - Running setup.py install for mozprofile 11:23:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:23:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:23:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:23:42 INFO - Running setup.py install for mozrunner 11:23:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:23:42 INFO - Running setup.py install for mozscreenshot 11:23:42 INFO - Running setup.py install for moztest 11:23:43 INFO - Running setup.py install for mozversion 11:23:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:23:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:23:43 INFO - Cleaning up... 11:23:43 INFO - Return code: 0 11:23:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:23:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:43 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/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 0x10b9bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90e1492b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79ad30>, '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 0x10b6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b78fbe8>, '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.x7H4PguVn5/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.HQCPL0jPez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 11:23:43 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:23:43 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:23:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x7H4PguVn5/Render', 11:23:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:43 INFO - 'HOME': '/Users/cltbld', 11:23:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:43 INFO - 'LOGNAME': 'cltbld', 11:23:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:43 INFO - 'MOZ_NO_REMOTE': '1', 11:23:43 INFO - 'NO_EM_RESTART': '1', 11:23:43 INFO - 'PAGER': '/bin/cat', 11:23:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:43 INFO - 'PWD': '/builds/slave/test', 11:23:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:43 INFO - 'SHELL': '/bin/bash', 11:23:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HQCPL0jPez/Listeners', 11:23:43 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 11:23:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:43 INFO - 'USER': 'cltbld', 11:23:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:43 INFO - 'XPC_FLAGS': '0x0', 11:23:43 INFO - 'XPC_SERVICE_NAME': '0', 11:23:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:23:43 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-WTKhsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:23:43 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)) 11:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:23:43 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-8LVqXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:23:43 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)) 11:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:23:43 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-0jrNWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:23:43 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)) 11:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:23:43 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-krGZGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:23:43 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)) 11:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:23:43 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-QX2SCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:23:44 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)) 11:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:23:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-OxdvsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:23:44 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)) 11:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:23:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-VfzKen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:23:44 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)) 11:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:23:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-h0gRth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:23:44 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)) 11:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:23:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-YthnAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:23:44 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)) 11:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:23:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-SWi8Ap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:23:44 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)) 11:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:23:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-60MPNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:23:44 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)) 11:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:23:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-h6M7s0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:23:44 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)) 11:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:23:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-l4sbJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:23:44 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)) 11:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:23:44 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-11kV_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:23:45 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)) 11:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:45 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-4nqTk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:45 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)) 11:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:23:45 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-m8bG9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:23:45 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)) 11:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:23:45 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-Bfce77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:23:45 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)) 11:23:45 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)) 11:23:45 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)) 11:23:45 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)) 11:23:45 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)) 11:23:45 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)) 11:23:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:23:45 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 11:23:45 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 11:23:45 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 11:23:45 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 11:23:46 INFO - Downloading blessings-1.6.tar.gz 11:23:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:23:46 INFO - Installing collected packages: blessings 11:23:46 INFO - Running setup.py install for blessings 11:23:46 INFO - Successfully installed blessings 11:23:46 INFO - Cleaning up... 11:23:47 INFO - Return code: 0 11:23:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:23:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:23:47 INFO - Reading from file tmpfile_stdout 11:23:47 INFO - Current package versions: 11:23:47 INFO - blessings == 1.6 11:23:47 INFO - blobuploader == 1.2.4 11:23:47 INFO - docopt == 0.6.1 11:23:47 INFO - functools32 == 3.2.3-2 11:23:47 INFO - jsonschema == 2.5.1 11:23:47 INFO - manifestparser == 1.1 11:23:47 INFO - mozInstall == 1.12 11:23:47 INFO - mozcrash == 1.0 11:23:47 INFO - mozdebug == 0.1 11:23:47 INFO - mozdevice == 0.49 11:23:47 INFO - mozfile == 1.2 11:23:47 INFO - mozhttpd == 0.7 11:23:47 INFO - mozinfo == 0.9 11:23:47 INFO - mozleak == 0.1 11:23:47 INFO - mozlog == 3.4 11:23:47 INFO - moznetwork == 0.27 11:23:47 INFO - mozprocess == 0.25 11:23:47 INFO - mozprofile == 0.28 11:23:47 INFO - mozrunner == 6.13 11:23:47 INFO - mozscreenshot == 0.1 11:23:47 INFO - mozsystemmonitor == 0.3 11:23:47 INFO - moztest == 0.8 11:23:47 INFO - mozversion == 1.4 11:23:47 INFO - psutil == 3.1.1 11:23:47 INFO - requests == 1.2.3 11:23:47 INFO - wsgiref == 0.1.2 11:23:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:23:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:47 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/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 0x10b9bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90e1492b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79ad30>, '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 0x10b6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b78fbe8>, '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.x7H4PguVn5/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.HQCPL0jPez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 11:23:47 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:23:47 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:23:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x7H4PguVn5/Render', 11:23:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:47 INFO - 'HOME': '/Users/cltbld', 11:23:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:47 INFO - 'LOGNAME': 'cltbld', 11:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:47 INFO - 'MOZ_NO_REMOTE': '1', 11:23:47 INFO - 'NO_EM_RESTART': '1', 11:23:47 INFO - 'PAGER': '/bin/cat', 11:23:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:47 INFO - 'PWD': '/builds/slave/test', 11:23:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:47 INFO - 'SHELL': '/bin/bash', 11:23:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HQCPL0jPez/Listeners', 11:23:47 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 11:23:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:47 INFO - 'USER': 'cltbld', 11:23:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:47 INFO - 'XPC_FLAGS': '0x0', 11:23:47 INFO - 'XPC_SERVICE_NAME': '0', 11:23:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:47 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)) 11:23:47 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)) 11:23:47 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)) 11:23:47 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)) 11:23:47 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)) 11:23:47 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)) 11:23:47 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)) 11:23:47 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)) 11:23:47 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)) 11:23:47 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)) 11:23:47 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)) 11:23:47 INFO - Cleaning up... 11:23:47 INFO - Return code: 0 11:23:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:23:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10b9bcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9bf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90e1492b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b79ad30>, '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 0x10b6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b78fbe8>, '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.x7H4PguVn5/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.HQCPL0jPez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 11:23:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:23:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x7H4PguVn5/Render', 11:23:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:47 INFO - 'HOME': '/Users/cltbld', 11:23:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:47 INFO - 'LOGNAME': 'cltbld', 11:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:47 INFO - 'MOZ_NO_REMOTE': '1', 11:23:47 INFO - 'NO_EM_RESTART': '1', 11:23:47 INFO - 'PAGER': '/bin/cat', 11:23:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:47 INFO - 'PWD': '/builds/slave/test', 11:23:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:47 INFO - 'SHELL': '/bin/bash', 11:23:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HQCPL0jPez/Listeners', 11:23:47 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 11:23:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:47 INFO - 'USER': 'cltbld', 11:23:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:47 INFO - 'XPC_FLAGS': '0x0', 11:23:47 INFO - 'XPC_SERVICE_NAME': '0', 11:23:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:23:47 INFO - Cleaning up... 11:23:47 INFO - Return code: 0 11:23:47 INFO - Running post-action listener: _resource_record_post_action 11:23:47 INFO - Running post-action listener: _start_resource_monitoring 11:23:47 INFO - Starting resource monitoring. 11:23:47 INFO - [mozharness: 2017-05-26 18:23:47.876036Z] Finished create-virtualenv step (success) 11:23:47 INFO - [mozharness: 2017-05-26 18:23:47.876970Z] Running install step. 11:23:47 INFO - Running pre-action listener: _resource_record_pre_action 11:23:47 INFO - Running main action method: install 11:23:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:23:48 INFO - Reading from file tmpfile_stdout 11:23:48 INFO - Detecting whether we're running mozinstall >=1.0... 11:23:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:23:48 INFO - Reading from file tmpfile_stdout 11:23:48 INFO - Output received: 11:23:48 INFO - Usage: mozinstall [options] installer 11:23:48 INFO - Options: 11:23:48 INFO - -h, --help show this help message and exit 11:23:48 INFO - -d DEST, --destination=DEST 11:23:48 INFO - Directory to install application into. [default: 11:23:48 INFO - "/builds/slave/test"] 11:23:48 INFO - --app=APP Application being installed. [default: firefox] 11:23:48 INFO - mkdir: /builds/slave/test/build/application 11:23:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 11:24:08 INFO - Reading from file tmpfile_stdout 11:24:08 INFO - Output received: 11:24:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:24:08 INFO - Running post-action listener: _resource_record_post_action 11:24:08 INFO - [mozharness: 2017-05-26 18:24:08.025511Z] Finished install step (success) 11:24:08 INFO - [mozharness: 2017-05-26 18:24:08.025644Z] Running run-tests step. 11:24:08 INFO - Running pre-action listener: _resource_record_pre_action 11:24:08 INFO - Running main action method: run_tests 11:24:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:24:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:24:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:24:08 INFO - 2017-05-26 11:24:08.040 screenresolution[1621:7318] starting screenresolution argv=screenresolution get 11:24:08 INFO - 2017-05-26 11:24:08.042 screenresolution[1621:7318] Display 0: 1600x1200x32@60 11:24:08 INFO - 2017-05-26 11:24:08.048 screenresolution[1622:7321] starting screenresolution argv=screenresolution list 11:24:08 INFO - Available Modes on Display 0 11:24:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:24:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:24:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:24:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:24:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:24:08 INFO - Intel Iris: 11:24:08 INFO - Chipset Model: Intel Iris 11:24:08 INFO - Type: GPU 11:24:08 INFO - Bus: Built-In 11:24:08 INFO - VRAM (Dynamic, Max): 1536 MB 11:24:08 INFO - Vendor: Intel (0x8086) 11:24:08 INFO - Device ID: 0x0a2e 11:24:08 INFO - Revision ID: 0x0009 11:24:08 INFO - Displays: 11:24:08 INFO - Display: 11:24:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:24:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:24:08 INFO - Display Serial Number: 1600x1200 60 11:24:08 INFO - Main Display: Yes 11:24:08 INFO - Mirror: Off 11:24:08 INFO - Online: Yes 11:24:08 INFO - Rotation: Supported 11:24:08 INFO - Return code: 0 11:24:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:24:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:24:08 INFO - Python 2.7.3 11:24:08 INFO - Return code: 0 11:24:08 INFO - grabbing minidump binary from tooltool 11:24:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:24:08 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 0x10b79ad30>, '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 0x10b6c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b78fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:24:08 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 11:24:08 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 11:24:08 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 11:24:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:24:08 INFO - Return code: 0 11:24:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:24:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:24:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:24:08 INFO - ENV: RUST_BACKTRACE is now 1 11:24:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:24:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:24:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0284', '--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 11:24:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0284 --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 11:24:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x7H4PguVn5/Render', 11:24:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:24:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:24:08 INFO - 'HOME': '/Users/cltbld', 11:24:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:24:08 INFO - 'LOGNAME': 'cltbld', 11:24:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:24:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:24:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:08 INFO - 'MOZ_NO_REMOTE': '1', 11:24:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:24:08 INFO - 'NO_EM_RESTART': '1', 11:24:08 INFO - 'PAGER': '/bin/cat', 11:24:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:24:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:08 INFO - 'PWD': '/builds/slave/test', 11:24:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:24:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:24:08 INFO - 'RUST_BACKTRACE': '1', 11:24:08 INFO - 'SHELL': '/bin/bash', 11:24:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HQCPL0jPez/Listeners', 11:24:08 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 11:24:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:24:08 INFO - 'USER': 'cltbld', 11:24:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:24:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:24:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:08 INFO - 'XPC_FLAGS': '0x0', 11:24:08 INFO - 'XPC_SERVICE_NAME': '0', 11:24:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:24:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0284', '--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 11:24:08 INFO - mozversion application_buildid: 20170526100115 11:24:08 INFO - mozversion application_changeset: 9baa8e24cc9cba93a1fbc1fc3a31ce19ed7e2766 11:24:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:24:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:24:08 INFO - mozversion application_name: Firefox 11:24:08 INFO - mozversion application_remotingname: firefox-dev 11:24:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:24:08 INFO - mozversion application_vendor: Mozilla 11:24:08 INFO - mozversion application_version: 54.0 11:24:08 INFO - mozversion platform_buildid: 20170526100115 11:24:08 INFO - mozversion platform_changeset: 9baa8e24cc9cba93a1fbc1fc3a31ce19ed7e2766 11:24:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:24:08 INFO - mozversion platform_version: 54.0 11:24:08 INFO - using testdate: 1495823048 11:24:08 INFO - actual date: 1495823048 11:24:08 INFO - starting webserver on 'localhost:49221' 11:24:08 INFO - SUITE-START | Running 2 tests 11:24:08 INFO - TEST-START | dromaeo_css 11:24:08 INFO - operating with platform_type : mac_ 11:24:08 INFO - Initialising browser for dromaeo_css test... 11:24:08 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpahXIuP/profile http://localhost:49221/getInfo.html) 11:24:11 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 11:24:11 INFO - PROCESS | 1632 | colorDepth:24 11:24:11 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 11:24:11 INFO - PROCESS | 1632 | __metrics 11:24:11 INFO - PROCESS | 1632 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:24:11 INFO - PROCESS | 1632 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:24:11 INFO - TEST-INFO | 1632: exit 0 11:24:11 INFO - Browser initialized. 11:24:11 INFO - Running cycle 1/1 for dromaeo_css test... 11:24:11 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpahXIuP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:24:23 INFO - PROCESS | 1635 | RSS: Main: 194002944 11:24:23 INFO - PROCESS | 1635 | 11:27:27 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49221/tests/dromaeo/cssquery-ext.html) 11:27:29 INFO - PROCESS | 1635 | RSS: Main: 323141632 11:27:29 INFO - PROCESS | 1635 | 11:30:32 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-ext.html (next: http://localhost:49221/tests/dromaeo/cssquery-jquery.html) 11:30:33 INFO - PROCESS | 1635 | RSS: Main: 328863744 11:30:33 INFO - PROCESS | 1635 | 11:33:35 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49221/tests/dromaeo/cssquery-mootools.html) 11:33:35 INFO - PROCESS | 1635 | RSS: Main: 324358144 11:33:35 INFO - PROCESS | 1635 | 11:36:38 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49221/tests/dromaeo/cssquery-prototype.html) 11:36:38 INFO - PROCESS | 1635 | RSS: Main: 295174144 11:36:38 INFO - PROCESS | 1635 | 11:39:42 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49221/tests/dromaeo/cssquery-yui.html) 11:39:42 INFO - PROCESS | 1635 | RSS: Main: 295874560 11:39:42 INFO - PROCESS | 1635 | 11:42:46 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-yui.html (next: http://localhost:49221/tests/dromaeo/cssquery-dojo.html) 11:42:46 INFO - PROCESS | 1635 | RSS: Main: 429748224 11:42:46 INFO - PROCESS | 1635 | 11:42:46 INFO - PROCESS | 1635 | __start_tp_report 11:42:46 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 11:42:46 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 11:42:46 INFO - PROCESS | 1635 | |i|pagename|runs| 11:42:46 INFO - PROCESS | 1635 | |0;dojo.html;3405.3700065487883;3418.8632454701806;3432.4508078707413;3466.907924003937;3476;4805.855824325267;5497.697626630534;5705.115707065406;5829.912551311723;5859.531237500995;5784.537237021039;5832.591718579701;5851.444112809287;5874.853128671785;5877.70611469427;5301.5228629423345;5324.5740340772645;5330.28041214435;5330.973345133279;5380.488853558905;5215.322008138936;5222.791088356461;5262.578993680516;5391.56867450603;5399;5302.204669299597;5317.9202311965155;5318.680879147263;5324.361076670785;5353.223782551528;5388.191771234307;5401.567874570025;5401.864953376158;5403.864903377408;5413.323334583177;5149.356330458692;5240.161574148118;5309.920351194735;5313.326485751578;5359.785608575652;5733.885322293572;5751.568632352549;5760.711964401763;5769.278840144982;5770;6149.169862068608;6233;6291.5281353898645;6300.527460440485;6323.494120470351;4728.385309909724;6335.176427064498;6336.271328797156;6345.302016778151;6350.618962862197;5980.491658209014;6050.092486127072;6061.999999999956;6064.575479716377;6072.635641861502;6211.503079753609;6258.780942666985;6294.716737746812;6311.400416960381;6349.55553111287;5286.524212820787;6321.1150438938585;6328.525360597973;6333.524985626096;6336.809895703136;6270.49836013127;6276.152719382825;6319.936800631935;6323.936760632427;6334.986402175629;6165.383461653799;6204.81385558434;6268.59254148488;6280.309165991737;6300;6295.276043255084;6314.0844577536;6326.430621244018;6327.462165715873;6336.58812177207;4878.95121048792;4883.438404583448;4884.998575292057;4893.363862697897;4907.558319751168;4227.556106608762;4291.29193683045;4314.56854314566;4369.191699535596;4393.868183954422;5862;5872.970635146797;5890.793822216202;5897.2333596633025;5920.378360272195;6292.748290068346;6318.462930650854;6320.62076275425;6334.746610135635;6339.207599050119;5843.883122337529;5855.473007429352;5859.2382990211;5862.003459411911;5886.558508111909;5308.362996440374;5412.269343638637;5462.644928079662;5489.231507588941;5520.613557051048;6435.195600549931;6475.935240647722;6487.351264873475;6487.870242595027;6507.023946407982;6296.275928268308;6296.559240853279;6304.65324407162;6305.96847015762;6306.495480361468;6255.499560035278;6403.167588213502;6490.545661803629;6492.967535162294;6497.220333560003;6246.875062498633;6248.468880145057;6254.843628909313;6256.092866534418;6265.874682506415;6142.468080853617;6234.127222188807;6278.560500764903;6279.529035322278;6302.275238347465;6237.812865614039;6300.495960323255;6302.243730752339;6326.2092238470195;6327.050942358683;6260.968695156495;6305.5901366411035;6311.96844015777;6318.115463834974;6338.556301058881;5959.314678811818;5991.101334799815;6072.969635151796;6126.309589765887;6126.681223142682;6266.154069200782;6272.40412160844;6274.560780745486;6274.905876411949;6275.937240627718;6038.973374526253;6104.114903338902;6162.969185154046;6176.969115154396;6202.937970620236 11:42:46 INFO - PROCESS | 1635 | |1;ext.html;661.5600625583986;783.5925318834206;837.5686521441456;892.6875593542261;907.5825120444595;28470.14589562311;41627.79186104069;41694.16611667765;41696.37455438163;41825.581744182586;42867.99924005321;42881.57118428815;42902.57097429025;42958.35562466565;43217.351739723854;7634.847303053935;9591.520423978807;9743.658971935984;9757.365771224882;9758.512074396287;7707.537547747125;7803.882941755878;7816.648250828726;7820.687172513092;7821.374290056782;11771.411429428537;12026.15816892818;12181.269123852553;12193.146479746378;12216.450259738289;11891.04871610269;11924.642260732191;11942.343171125542;11974.64076077719;12007.519699212022;11516.654500365003;11596.942015289955;11618.941905290503;11621.825672614917;11626.069914406828;40230.99422514431;40331.19337613261;40416.595834041575;40482.595174048176;40536.594634053574;3919.8432062717457;3930.253251882133;3942.763434193929;3983.601639836022;3991.560928297885;27066.86466567674;27900.720992790015;29625.70374296251;29794.10617681473;30004;3060.3573249617607;3159.7788154829173;3299.835008249578;3307.834608269577;3310.7351411886993;341660;343938.28030859935;346099;346497;355006;53274;53501;53554.46445535573;53574.73212633912;53637.927241454956;55640.72179639076;55661.44338556644;55698.16452753212;55719.721401393545;55727.164092538645;14716.779248311284;15091;15116.470923517623;15124.773128403083;15145.394184232728;12777.425015874307;12814.35928203586;12818;12844.100912936192;12858.357082145856;10605.257631965842;10807.67576972692;11968.880311196777;12470.376481175905;12524.373781310898;4513.638908887281;4708.529147085264;5203.453637368022;5373.919391209135;5423.918641220384;1041.182671602788;1110.3448965110624;1190.6070996571111;1192.6899006258436;1226.0804396702474;27231.455370892472;27507;27640;27704.445911081664;27796.86101569479;1137.5051852444212;1196.3958201108385;1276.6955645060755;1319.4326439630859;1322.3916998180753;13469.057165998482;13906.235157066256;14232.573022809125;14233.572992810232;14616.415343386358;482922;498993;500180;614770;616621;41050.589494105276;41570.79214603908;48148;48439.78900527445;48530.02939941181;1248.6628610275175;1426.1015560197218;1436.346462359624;1454.6072560408843;1455.2432734977751;23310.88344558266;26622.06822761194;26999.730002700508;27015.324616884736;27971.720282796912;40497.392539111344;40905.79547102245;40947;40960.97597560085;41201;367695;369164;369451;370037;497591;24688.629670554597;32002.19994500156;38842.6115738839;39465.60534394619;39525.20949580935;45901.31148032849;46512;47785.04429911452;48107.75946120247;48418.273725893436;31497.8425107873;32631.510527341634;32847.67152328446;33821.32357352888;33999.49000764941;27496.58755118715;30714;32193.034208973768;32481.02556923296;33039.00882973515;38814.80592597019;39660.2067958645;39739.80130099447;39758.40362394509;39766.20467590574;33097.338053238316;33739;34010.82994585011;34575.30849383049;34607;24546.263612091665;25883.741162588885;25991.870040650432;26227.868860655573;26539 11:42:46 INFO - PROCESS | 1635 | |2;jquery.html;412.8864562245383;432.6495538613721;436.670791310538;439.83286351186547;450.30877602879565;18117.547061323472;19872.801271987293;20735.170593176274;22050;22155.778442215593;21777;22469.21357752479;22675.09299628017;22992.965316560752;23141.190058347962;6461.450776683988;6765.0867132937165;6783.152105986739;6814.250432452426;7008.159020917472;6773.898391524106;6880.965595172017;6941.305869413069;6951.687174077153;6954.30456954303;6586.407223349897;6776.69504872282;6873.690683919235;6952.652367381636;6957.5129740918155;6491.091247225392;6760;6779.62712050837;6809;6827.2831352708;6604.075429439882;6613.37172968572;6674.833129171761;6682.398584127452;6697.93302066978;27366.726332736616;27707.861460692566;27748.86125569359;27751.028713994914;28148.577771333446;3046.512557990733;3064.4024415239037;3066.861991210401;3069.815811051344;3081.6610172880964;15597.376104955789;16212.75680864788;16353;17556.473305800846;17929.551761205945;1917.338518211215;2054.3734161080865;2096.622607930572;2109.1457959526415;2114.4819519217735;135096;137217;143159.2842035783;144128.99999999895;146118;17543.21055552503;19310.80689193118;20044.298449554197;20430.18279268842;20507.28224512165;26242.343941401235;31387.372252554822;31647.36705265928;32061.67938320587;32112.518312225337;8810.911890881009;10137.797244055078;10191.337563058378;10456.163506919429;10491.213159013105;8685.782855428539;8695.956520217358;8709.604858663919;8805;8840.33697472692;9404;9497.810043799223;9540.045995400404;9550.856737148948;9578.808423831484;3606.314800187956;4012.8563359443;4041.1917616476235;4097.426360309559;4126.607972242632;974.5030034682221;1028.1928685981468;1028.3624153025173;1043.3218408034838;1048.3814549415881;20974.790252097588;21373.358799236048;21425.571488570455;21457.570848582942;21486.462838429165;1031.5718976624767;1034.4672493665769;1048;1050.6847945616285;1064.6593090211059;9390.624375024923;10654.73363165927;11105.888941110647;11239;11258.268212566158;770263;787130;787905;790441.0477947573;809969;134599;135132;137205.62794372128;141028.2948585271;154337;1196.4795314038422;1202.6512311429587;1226.9509219631293;1229.3423018684782;1231.735176936963;17768;18607.348742793936;18727.438176854717;18774;18803.05984700787;61976;62490.375096250275;62696.686516567126;63101;63867.68066159826;86707.1329286699;87176;88018.55990720005;89428;90428.64357034519;9541.47521886303;11004.944975275072;11510.48202830858;11633.185677002528;11792.76414471723;13264.46942122343;13335.533256335983;13359;13755.243461609307;13784.93107534496;11132.721681958015;11305.830412543983;11588.826167607662;11739.47172377228;11819.468123934616;10700.464976751287;10833.29583577065;10931.125509959025;11026.724331891446;11121.83317250258;11158.832617510581;11683.474243659226;11896.464659090534;11958.820617690915;12106.636800895987;10280.640177593748;11189.328640281608;11509.079273658255;11796.823047654463;11830.112741544246;11295.774084518427;11379.870535602775;11437.313761174024;11605;11652.708682282671 11:42:46 INFO - PROCESS | 1635 | |3;mootools.html;1365.3309878159705;1587.54754894855;1611.0655819624612;1612.1052815687297;1633.3956436118633;9227.07729227077;9675.758106047353;10901.018908298262;10957.287776294535;11383;11048.779024419528;11174;11175.664730058112;11178.32930024199;11213.495392707327;1997.530580313625;2361.2325994051916;2392.306231192952;2407.205622144697;2409.1567951217107;2086.1655337864822;2253.290213582716;2348.7064116985375;2358.41039740065;2359.3393849722106;2368.7749663782;2387.85672859629;2390.438247011951;2396.772306630867;2398.1846172301503;2343.215022967308;2349.0721165139676;2351.0595761695286;2354.776296251853;2355.4935688826986;1932.4492519631979;2308.157522504274;2387.85672859629;2388.199953015742;2389.0802041214256;11217.934296241843;11297.98318151362;11313.264637798598;11319.151063670257;11325.980661740401;5592.217089607459;5752.309722833245;5764.625299355528;5765.365809760923;5780.479756821865;3295.2001989554865;3378.6283508814013;3524.577050753926;3586.8923932282073;3587.6950459211;1123.6516679829278;1181.362064485177;1196.7785959597502;1196.8803119688048;1214.8056310990198;15409.768853467207;15573.999999999887;15579.98730082541;15584.922075389664;15593.844061559352;4738.740626543495;4747.765580948978;4805.783739731721;4862.659613826998;4914;12973.962083033335;12979.610611681665;12995.28525931064;13005.414756335987;13005.544805931937;5712.857428514314;5812.3025236971835;5825.679587622722;5830.387809279964;5842.474177324061;4400.471943366816;4951.628627852925;5148.6910785353;5169.069567477815;5267.525922666979;3763.8682646107254;3844.692424606025;3856.9614303857165;3877.515310586177;3902.765834049966;1003.1072345612411;1004.2618675273814;1015.8577799108131;1018.8267994440963;1026.4200726589602;951.790606066678;971.9271054670928;979.1872745621117;980.529345913951;1000.2448151645461;6701.664916754143;6895.172579290517;6896.793096207121;6917.688704008332;6944.930550694529;981.1513041219293;1011.3540685827633;1017.353980222564;1018.0938964321759;1020.9081182693593;3647.7446578739764;4155.9194609401775;4231.132617813341;4406.206882761134;4435.556444355539;11494.448249486213;13428.798568021488;13682.973776966768;13855.37650805696;13865.722685546232;13764.17414955106;13767;13780.793288100687;13785.035047546575;13800;8624.69813556535;9854.605815767432;10074.44590547527;10324.225683073799;10336.224783141295;6639.269680335063;6729.1252137221845;6754.932450675627;6763.52655314143;6810.046593476818;11983.580574679845;12039.939800300943;12355.196912200678;12373.876261237272;12375.195612285534;10687.839682404614;12286.754264914473;12395.256284622952;12505.562305319634;12563.811542827048;7503.399728021855;8672.089430609947;8734.301255899385;8749.518776467356;8763.123687631185;10264.948675256575;10456.424896630455;10532.21008424356;10547.472626368804;10548.894511054792;8638.91361086381;8778.51718155482;8864.072935017532;8991.82016359656;9313.022132675971;8641.438306510057;8663.26362259215;8703.042665306684;8727.738167854974;8772.298216142566;8007.119216886254;8165.632546535539;8167.795805104683;8179.222973817716;8187.79530511742;5957.940420595739;5998.850028749316;6003.219581454558;6152.876942461216;6195.628262304276;5996;6031.547633927561;6173.629582225081;6246.156768836149;6380.234371875219 11:42:46 INFO - PROCESS | 1635 | |4;prototype.html;379.31344266876954;386.88393481955416;390.0288282177379;392.8035982008994;399.69623086454294;5004.099262132815;5041.621878359119;5048.368953880769;5124.565831230164;5185.470286265551;4102.774347410891;4943.752812359386;5017.444592176428;5034.09386310464;5048.217526283423;4115.73247738896;4184.623383895449;4234.767087810169;4241.554636763141;4248.97875510622;3660.2313514162056;3960.980195099035;4044.4944381952255;4202.285611446062;4243.790519701885;4236.597523235288;4302.204092242945;4317.330813723862;4335.024619460612;4347.2826983547675;3404.931901361959;3713.9119406413115;4283.100548884722;4309.719868208556;4317.287647538168;3429.216243340551;3870.845166193377;3890.513685789276;3894.55212650546;3900.7074469414906;4628.398308219944;4788.377510923592;4869.805207791684;5033;5059.392872855281;4495.34817451471;4589.518100599423;4932.090066860534;4939.431965323962;4967.304577359137;4763.21379482694;5037.974810125926;5184.403793563751;5409.675419474805;5436;3756.9624303756887;3958.832933653066;4100.5899410059055;4301.65586753059;4365.49796773372;4846.39420072491;5487.945120548783;5606.4307589314985;5715.685637289947;5795.391483894173;3661.7802931823644;3790.753601015925;3960.465337179444;4158.771267580312;4282;3918.49059622253;4469.664775141878;4471.9043834260165;4481.484629267611;4510.421352526655;4346.326319420495;4410.059573787523;4737.8341758038305;4839.225723884161;4846.636502262344;4994.776279811469;5288.7091209983055;5427.83716488506;5457.617966742369;5538.473844984801;1377.7657798174334;1433.9317208679392;1487.6355292953297;1499.3777582303221;1530.9540713778604;1090.3185509056839;1108.728361551425;1143.4797167288864;1160.118310084325;1177.9823302650468;4436.534163912743;4460.487043989918;4801.591864691471;4894.510548945148;4897.111742187917;5664.97167514168;5681.943180568224;5682.54539636828;5714.7999820006935;5773.39379365169;4203.8031644650655;4330.566943305707;4413.668974826901;4445.621857224271;4758.262469317261;2626.755956393852;2636.8417894926365;2771.5426954552677;2879.4960881845595;2880.9279768005968;5140.203268493389;5369.5167434931045;5761.107028410603;5848.678322692293;5861.912071318934;5043.899122017539;5208.947910520847;5380.757865896044;5592.720363981784;5708.257926469615;5555.250041244381;5565.387807341189;5632.7746890123935;5723.713814309268;5739.9139012915675;4961.70229786214;5135.666181698178;5218.0346635870965;5550.75021624036;5611.727250463568;4824.140687450096;4900.926486102782;5131.666441681279;5327.733613319241;5525.143602741581;5019.4690619361445;5602.4957753802355;5673.319201695823;5751.798687045934;5767.884642307046;3953.4465174874967;4088.672906167559;4390.736555806662;4391.8462853799965;4392.428984232157;5130.8717282066755;5439.062906030699;5461.095316848314;5541.667499950016;5588.944110558842;5112.74436278192;5354.3842458117815;5361.35663720356;5411.375482890873;5431.511163995259;4619.607333376701;4700.882477937942;4845.2449351752675;5111.821086261963;5155.458676839028;4664.3441578239235;4692.178868698033;5218.295530103387;5309.283246761638;5389.784408623611;4782.497837726988;4920.434150072644;5159.871003224949;5505.532029777513;5529.834104976857;4878.219484882402;5021.598272138147;5170.594269838908;5258.793794143694;5422.945770542402 11:42:46 INFO - PROCESS | 1635 | |5;yui.html;732.388455639541;769.9692012319507;780.5121798875703;846.2595229174473;854.0520022774721;13704;15532.767008494882;15801.209939503022;16534.503964881056;16817.83182168178;14837;15202.847971520308;15225;15358;15371.538853834374;1000.1398797034544;1027.3579013116803;1028.1466382902174;1029.0224286927412;1029.953652085658;839.5340585974782;870.3994243971671;1030.4950574218653;1034.3483605328634;1034.7361422837166;1069.8341757027674;1071.3893080943865;1072.7210925159475;1073.8657667791526;1074.1084899533369;1063.0220197418394;1063.1547919404093;1063.457636605333;1065.4938904020605;1065.5737704918017;795.5783434779576;795.8806179073127;796.2952786783738;799.3605115907309;799.3724925933114;16305;16323;16342.836571634369;17168.656626867392;17488.825111748847;13483.932580337134;13509.121907076009;13722;13728.519501817489;13764.65588360289;11835.845846292721;11892.405379730979;11916.34460104694;11946.581869634609;12128.272303661808;3703.407454807218;3708.388115960863;3716.7955762433057;3724.3854763963914;3733.5146430964073;83801;109994;117444.41277793642;119022;121174;11090.611828585961;11147.777044459066;11218.102551795837;11312.151588630886;11381.886181138083;20711.58576828485;23704.288871333887;23831.880840595684;24005.51988960211;24084;7921.247481489249;7938.444308898322;8023.584080745473;8037.477563958324;8071.757847264592;4330.783460826946;4355.651547876162;4372.431583894074;4514.074614703978;4648.814047438133;3480.756347055682;3566.5720113586535;3579.6957258633306;3580.874669386611;3586.4082426399873;509.0648876569878;624.0358645892107;630.7224821078711;631.857831987799;632.5350867112669;677.8881484554993;694.9166100067923;700.352174238827;710.5807573531641;719.9352058314777;9178.31162662803;9818.558164882597;9914.851277230848;10526.368417894952;10621.044106030495;650.4016304999412;655.6983787457823;706.6502081469705;715.4777012780595;723.565860483716;4487.192305385062;4973.154563724175;4980.750962451935;4992.875178120504;5032.949670503322;67229.66385168042;89838;90405;91438.08561914296;93135.53432232795;61488;73477;74166.6291668549;83475;84031.31937361219;51938;72419.27580724125;74301.62849185719;75105;75644.62177689077;10801.783964320826;11148.335377344458;11167.441627918734;11233.887661123612;11253.549858005588;46331.76834115808;48726.78183045452;48889;49095;50334.49665503445;61836;62302.37697622966;62364.37635623586;63060.36939630729;64651;12898.935505322414;13009.934950325567;13249.735005299648;13750.037497375088;13917.164970101827;14825.481108161162;15068.246587670354;15305.846941530443;15410.152441615819;16117.194140293173;13732.734098873574;14034;14117.152970821784;14217.360218790385;14224.431022758974;13796.379162937492;14251;14275.42898284057;14338.784918226027;14599.781003285172;11189.888101118884;13563.660908477366;13716.519921802688;13830.37763300674;13864.306784660928;8059.597020148852;9224.354295199271;9278.72163835086;9449;9788.216942644714;10268.58925642966;10503.42231177262;11055.166808730248;11391;11405.486753096297 11:42:46 INFO - PROCESS | 1635 | __end_tp_report 11:42:46 INFO - PROCESS | 1635 | __start_cc_report 11:42:46 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,1363 11:42:46 INFO - PROCESS | 1635 | __end_cc_report 11:42:46 INFO - PROCESS | 1635 | __startTimestamp1495824166293__endTimestamp 11:42:46 INFO - PROCESS | 1635 | 11:42:46 INFO - PROCESS | 1635 | ------- Summary: start ------- 11:42:46 INFO - PROCESS | 1635 | Number of tests: 6 11:42:46 INFO - PROCESS | 1635 | 11:42:46 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5827.65 Median:6126.31 stddev:671.39 (11.0%) stddev-sans-first:646.00 11:42:46 INFO - PROCESS | 1635 | Values: 3405.4 3418.9 3432.5 3466.9 3476.0 4805.9 5497.7 5705.1 5829.9 5859.5 5784.5 5832.6 5851.4 5874.9 5877.7 5301.5 5324.6 5330.3 5331.0 5380.5 5215.3 5222.8 5262.6 5391.6 5399.0 5302.2 5317.9 5318.7 5324.4 5353.2 5388.2 5401.6 5401.9 5403.9 5413.3 5149.4 5240.2 5309.9 5313.3 5359.8 5733.9 5751.6 5760.7 5769.3 5770.0 6149.2 6233.0 6291.5 6300.5 6323.5 4728.4 6335.2 6336.3 6345.3 6350.6 5980.5 6050.1 6062.0 6064.6 6072.6 6211.5 6258.8 6294.7 6311.4 6349.6 5286.5 6321.1 6328.5 6333.5 6336.8 6270.5 6276.2 6319.9 6323.9 6335.0 6165.4 6204.8 6268.6 6280.3 6300.0 6295.3 6314.1 6326.4 6327.5 6336.6 4879.0 4883.4 4885.0 4893.4 4907.6 4227.6 4291.3 4314.6 4369.2 4393.9 5862.0 5873.0 5890.8 5897.2 5920.4 6292.7 6318.5 6320.6 6334.7 6339.2 5843.9 5855.5 5859.2 5862.0 5886.6 5308.4 5412.3 5462.6 5489.2 5520.6 6435.2 6475.9 6487.4 6487.9 6507.0 6296.3 6296.6 6304.7 6306.0 6306.5 6255.5 6403.2 6490.5 6493.0 6497.2 6246.9 6248.5 6254.8 6256.1 6265.9 6142.5 6234.1 6278.6 6279.5 6302.3 6237.8 6300.5 6302.2 6326.2 6327.1 6261.0 6305.6 6312.0 6318.1 6338.6 5959.3 5991.1 6073.0 6126.3 6126.7 6266.2 6272.4 6274.6 6274.9 6275.9 6039.0 6104.1 6163.0 6177.0 6202.9 11:42:46 INFO - PROCESS | 1635 | 11:42:46 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:57055.98 Median:187835.41 stddev:116627.30 (62.1%) stddev-sans-first:116877.61 11:42:46 INFO - PROCESS | 1635 | Values: 661.6 783.6 837.6 892.7 907.6 28470.1 41627.8 41694.2 41696.4 41825.6 42868.0 42881.6 42902.6 42958.4 43217.4 7634.8 9591.5 9743.7 9757.4 9758.5 7707.5 7803.9 7816.6 7820.7 7821.4 11771.4 12026.2 12181.3 12193.1 12216.5 11891.0 11924.6 11942.3 11974.6 12007.5 11516.7 11596.9 11618.9 11621.8 11626.1 40231.0 40331.2 40416.6 40482.6 40536.6 3919.8 3930.3 3942.8 3983.6 3991.6 27066.9 27900.7 29625.7 29794.1 30004.0 3060.4 3159.8 3299.8 3307.8 3310.7 341660.0 343938.3 346099.0 346497.0 355006.0 53274.0 53501.0 53554.5 53574.7 53637.9 55640.7 55661.4 55698.2 55719.7 55727.2 14716.8 15091.0 15116.5 15124.8 15145.4 12777.4 12814.4 12818.0 12844.1 12858.4 10605.3 10807.7 11968.9 12470.4 12524.4 4513.6 4708.5 5203.5 5373.9 5423.9 1041.2 1110.3 1190.6 1192.7 1226.1 27231.5 27507.0 27640.0 27704.4 27796.9 1137.5 1196.4 1276.7 1319.4 1322.4 13469.1 13906.2 14232.6 14233.6 14616.4 482922.0 498993.0 500180.0 614770.0 616621.0 41050.6 41570.8 48148.0 48439.8 48530.0 1248.7 1426.1 1436.3 1454.6 1455.2 23310.9 26622.1 26999.7 27015.3 27971.7 40497.4 40905.8 40947.0 40961.0 41201.0 367695.0 369164.0 369451.0 370037.0 497591.0 24688.6 32002.2 38842.6 39465.6 39525.2 45901.3 46512.0 47785.0 48107.8 48418.3 31497.8 32631.5 32847.7 33821.3 33999.5 27496.6 30714.0 32193.0 32481.0 33039.0 38814.8 39660.2 39739.8 39758.4 39766.2 33097.3 33739.0 34010.8 34575.3 34607.0 24546.3 25883.7 25991.9 26227.9 26539.0 11:42:46 INFO - PROCESS | 1635 | 11:42:46 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:43528.93 Median:21399.47 stddev:130736.12 (610.9%) stddev-sans-first:131062.78 11:42:46 INFO - PROCESS | 1635 | Values: 412.9 432.6 436.7 439.8 450.3 18117.5 19872.8 20735.2 22050.0 22155.8 21777.0 22469.2 22675.1 22993.0 23141.2 6461.5 6765.1 6783.2 6814.3 7008.2 6773.9 6881.0 6941.3 6951.7 6954.3 6586.4 6776.7 6873.7 6952.7 6957.5 6491.1 6760.0 6779.6 6809.0 6827.3 6604.1 6613.4 6674.8 6682.4 6697.9 27366.7 27707.9 27748.9 27751.0 28148.6 3046.5 3064.4 3066.9 3069.8 3081.7 15597.4 16212.8 16353.0 17556.5 17929.6 1917.3 2054.4 2096.6 2109.1 2114.5 135096.0 137217.0 143159.3 144129.0 146118.0 17543.2 19310.8 20044.3 20430.2 20507.3 26242.3 31387.4 31647.4 32061.7 32112.5 8810.9 10137.8 10191.3 10456.2 10491.2 8685.8 8696.0 8709.6 8805.0 8840.3 9404.0 9497.8 9540.0 9550.9 9578.8 3606.3 4012.9 4041.2 4097.4 4126.6 974.5 1028.2 1028.4 1043.3 1048.4 20974.8 21373.4 21425.6 21457.6 21486.5 1031.6 1034.5 1048.0 1050.7 1064.7 9390.6 10654.7 11105.9 11239.0 11258.3 770263.0 787130.0 787905.0 790441.0 809969.0 134599.0 135132.0 137205.6 141028.3 154337.0 1196.5 1202.7 1227.0 1229.3 1231.7 17768.0 18607.3 18727.4 18774.0 18803.1 61976.0 62490.4 62696.7 63101.0 63867.7 86707.1 87176.0 88018.6 89428.0 90428.6 9541.5 11004.9 11510.5 11633.2 11792.8 13264.5 13335.5 13359.0 13755.2 13784.9 11132.7 11305.8 11588.8 11739.5 11819.5 10700.5 10833.3 10931.1 11026.7 11121.8 11158.8 11683.5 11896.5 11958.8 12106.6 10280.6 11189.3 11509.1 11796.8 11830.1 11295.8 11379.9 11437.3 11605.0 11652.7 11:42:46 INFO - PROCESS | 1635 | 11:42:46 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:6760.33 Median:2391.37 stddev:4263.48 (178.3%) stddev-sans-first:4256.16 11:42:46 INFO - PROCESS | 1635 | Values: 1365.3 1587.5 1611.1 1612.1 1633.4 9227.1 9675.8 10901.0 10957.3 11383.0 11048.8 11174.0 11175.7 11178.3 11213.5 1997.5 2361.2 2392.3 2407.2 2409.2 2086.2 2253.3 2348.7 2358.4 2359.3 2368.8 2387.9 2390.4 2396.8 2398.2 2343.2 2349.1 2351.1 2354.8 2355.5 1932.4 2308.2 2387.9 2388.2 2389.1 11217.9 11298.0 11313.3 11319.2 11326.0 5592.2 5752.3 5764.6 5765.4 5780.5 3295.2 3378.6 3524.6 3586.9 3587.7 1123.7 1181.4 1196.8 1196.9 1214.8 15409.8 15574.0 15580.0 15584.9 15593.8 4738.7 4747.8 4805.8 4862.7 4914.0 12974.0 12979.6 12995.3 13005.4 13005.5 5712.9 5812.3 5825.7 5830.4 5842.5 4400.5 4951.6 5148.7 5169.1 5267.5 3763.9 3844.7 3857.0 3877.5 3902.8 1003.1 1004.3 1015.9 1018.8 1026.4 951.8 971.9 979.2 980.5 1000.2 6701.7 6895.2 6896.8 6917.7 6944.9 981.2 1011.4 1017.4 1018.1 1020.9 3647.7 4155.9 4231.1 4406.2 4435.6 11494.4 13428.8 13683.0 13855.4 13865.7 13764.2 13767.0 13780.8 13785.0 13800.0 8624.7 9854.6 10074.4 10324.2 10336.2 6639.3 6729.1 6754.9 6763.5 6810.0 11983.6 12039.9 12355.2 12373.9 12375.2 10687.8 12286.8 12395.3 12505.6 12563.8 7503.4 8672.1 8734.3 8749.5 8763.1 10264.9 10456.4 10532.2 10547.5 10548.9 8638.9 8778.5 8864.1 8991.8 9313.0 8641.4 8663.3 8703.0 8727.7 8772.3 8007.1 8165.6 8167.8 8179.2 8187.8 5957.9 5998.9 6003.2 6152.9 6195.6 5996.0 6031.5 6173.6 6246.2 6380.2 11:42:46 INFO - PROCESS | 1635 | 11:42:46 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4485.34 Median:4846.52 stddev:1254.91 (25.9%) stddev-sans-first:1220.00 11:42:46 INFO - PROCESS | 1635 | Values: 379.3 386.9 390.0 392.8 399.7 5004.1 5041.6 5048.4 5124.6 5185.5 4102.8 4943.8 5017.4 5034.1 5048.2 4115.7 4184.6 4234.8 4241.6 4249.0 3660.2 3961.0 4044.5 4202.3 4243.8 4236.6 4302.2 4317.3 4335.0 4347.3 3404.9 3713.9 4283.1 4309.7 4317.3 3429.2 3870.8 3890.5 3894.6 3900.7 4628.4 4788.4 4869.8 5033.0 5059.4 4495.3 4589.5 4932.1 4939.4 4967.3 4763.2 5038.0 5184.4 5409.7 5436.0 3757.0 3958.8 4100.6 4301.7 4365.5 4846.4 5487.9 5606.4 5715.7 5795.4 3661.8 3790.8 3960.5 4158.8 4282.0 3918.5 4469.7 4471.9 4481.5 4510.4 4346.3 4410.1 4737.8 4839.2 4846.6 4994.8 5288.7 5427.8 5457.6 5538.5 1377.8 1433.9 1487.6 1499.4 1531.0 1090.3 1108.7 1143.5 1160.1 1178.0 4436.5 4460.5 4801.6 4894.5 4897.1 5665.0 5681.9 5682.5 5714.8 5773.4 4203.8 4330.6 4413.7 4445.6 4758.3 2626.8 2636.8 2771.5 2879.5 2880.9 5140.2 5369.5 5761.1 5848.7 5861.9 5043.9 5208.9 5380.8 5592.7 5708.3 5555.3 5565.4 5632.8 5723.7 5739.9 4961.7 5135.7 5218.0 5550.8 5611.7 4824.1 4900.9 5131.7 5327.7 5525.1 5019.5 5602.5 5673.3 5751.8 5767.9 3953.4 4088.7 4390.7 4391.8 4392.4 5130.9 5439.1 5461.1 5541.7 5588.9 5112.7 5354.4 5361.4 5411.4 5431.5 4619.6 4700.9 4845.2 5111.8 5155.5 4664.3 4692.2 5218.3 5309.3 5389.8 4782.5 4920.4 5159.9 5505.5 5529.8 4878.2 5021.6 5170.6 5258.8 5422.9 11:42:46 INFO - PROCESS | 1635 | 11:42:46 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:19580.90 Median:23768.08 stddev:27183.81 (114.4%) stddev-sans-first:27223.23 11:42:46 INFO - PROCESS | 1635 | Values: 732.4 770.0 780.5 846.3 854.1 13704.0 15532.8 15801.2 16534.5 16817.8 14837.0 15202.8 15225.0 15358.0 15371.5 1000.1 1027.4 1028.1 1029.0 1030.0 839.5 870.4 1030.5 1034.3 1034.7 1069.8 1071.4 1072.7 1073.9 1074.1 1063.0 1063.2 1063.5 1065.5 1065.6 795.6 795.9 796.3 799.4 799.4 16305.0 16323.0 16342.8 17168.7 17488.8 13483.9 13509.1 13722.0 13728.5 13764.7 11835.8 11892.4 11916.3 11946.6 12128.3 3703.4 3708.4 3716.8 3724.4 3733.5 83801.0 109994.0 117444.4 119022.0 121174.0 11090.6 11147.8 11218.1 11312.2 11381.9 20711.6 23704.3 23831.9 24005.5 24084.0 7921.2 7938.4 8023.6 8037.5 8071.8 4330.8 4355.7 4372.4 4514.1 4648.8 3480.8 3566.6 3579.7 3580.9 3586.4 509.1 624.0 630.7 631.9 632.5 677.9 694.9 700.4 710.6 719.9 9178.3 9818.6 9914.9 10526.4 10621.0 650.4 655.7 706.7 715.5 723.6 4487.2 4973.2 4980.8 4992.9 5032.9 67229.7 89838.0 90405.0 91438.1 93135.5 61488.0 73477.0 74166.6 83475.0 84031.3 51938.0 72419.3 74301.6 75105.0 75644.6 10801.8 11148.3 11167.4 11233.9 11253.5 46331.8 48726.8 48889.0 49095.0 50334.5 61836.0 62302.4 62364.4 63060.4 64651.0 12898.9 13009.9 13249.7 13750.0 13917.2 14825.5 15068.2 15305.8 15410.2 16117.2 13732.7 14034.0 14117.2 14217.4 14224.4 13796.4 14251.0 14275.4 14338.8 14599.8 11189.9 13563.7 13716.5 13830.4 13864.3 8059.6 9224.4 9278.7 9449.0 9788.2 10268.6 10503.4 11055.2 11391.0 11405.5 11:42:46 INFO - PROCESS | 1635 | -------- Summary: end -------- 11:42:46 INFO - PROCESS | 1635 | 11:42:46 INFO - TEST-INFO | 1635: exit 0 11:42:46 INFO - TEST-OK | dromaeo_css | took 1118198ms 11:42:46 INFO - TEST-START | kraken 11:42:46 INFO - operating with platform_type : mac_ 11:42:46 INFO - Initialising browser for kraken test... 11:42:46 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpXMdiIH/profile http://localhost:49221/getInfo.html) 11:42:49 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 11:42:49 INFO - PROCESS | 1645 | colorDepth:24 11:42:49 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 11:42:49 INFO - PROCESS | 1645 | __metrics 11:42:49 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:42:49 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:42:49 INFO - TEST-INFO | 1645: exit 0 11:42:49 INFO - Browser initialized. 11:42:49 INFO - Running cycle 1/1 for kraken test... 11:42:49 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpXMdiIH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:43:01 INFO - PROCESS | 1647 | RSS: Main: 193880064 11:43:01 INFO - PROCESS | 1647 | 11:43:04 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection) 11:43:05 INFO - PROCESS | 1647 | RSS: Main: 245653504 11:43:05 INFO - PROCESS | 1647 | 11:43:08 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-dft) 11:43:08 INFO - PROCESS | 1647 | RSS: Main: 260390912 11:43:08 INFO - PROCESS | 1647 | 11:43:11 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-fft) 11:43:12 INFO - PROCESS | 1647 | RSS: Main: 357113856 11:43:12 INFO - PROCESS | 1647 | 11:43:14 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator) 11:43:14 INFO - PROCESS | 1647 | RSS: Main: 257826816 11:43:14 INFO - PROCESS | 1647 | 11:43:16 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:43:17 INFO - PROCESS | 1647 | RSS: Main: 259297280 11:43:17 INFO - PROCESS | 1647 | 11:43:20 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom) 11:43:21 INFO - PROCESS | 1647 | RSS: Main: 300240896 11:43:21 INFO - PROCESS | 1647 | 11:43:24 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate) 11:43:25 INFO - PROCESS | 1647 | RSS: Main: 301989888 11:43:25 INFO - PROCESS | 1647 | 11:43:27 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial) 11:43:28 INFO - PROCESS | 1647 | RSS: Main: 299479040 11:43:28 INFO - PROCESS | 1647 | 11:43:30 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:43:30 INFO - PROCESS | 1647 | RSS: Main: 276877312 11:43:30 INFO - PROCESS | 1647 | 11:43:32 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:43:33 INFO - PROCESS | 1647 | RSS: Main: 281313280 11:43:33 INFO - PROCESS | 1647 | 11:43:35 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:43:36 INFO - PROCESS | 1647 | RSS: Main: 287096832 11:43:36 INFO - PROCESS | 1647 | 11:43:38 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:43:39 INFO - PROCESS | 1647 | RSS: Main: 296022016 11:43:39 INFO - PROCESS | 1647 | 11:43:42 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:43:43 INFO - PROCESS | 1647 | RSS: Main: 294727680 11:43:43 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49221/tests/kraken/driver.html?testName=ai-astar) 11:43:45 INFO - PROCESS | 1647 | RSS: Main: 338948096 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | __start_tp_report 11:43:45 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 11:43:45 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 11:43:45 INFO - PROCESS | 1647 | |i|pagename|runs| 11:43:45 INFO - PROCESS | 1647 | |0;ai-astar;105;116;117;115;115;114;112;115;115;115 11:43:45 INFO - PROCESS | 1647 | |1;audio-beat-detection;142;121;110;143;112;113;112;108;129;108 11:43:45 INFO - PROCESS | 1647 | |2;audio-dft;185;184;185;187;185;186;185;186;185;183 11:43:45 INFO - PROCESS | 1647 | |3;audio-fft;83;58;60;89;63;61;62;58;73;60 11:43:45 INFO - PROCESS | 1647 | |4;audio-oscillator;85;69;87;70;70;69;82;68;69;82 11:43:45 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;90;85;85;86;102;136;86;86;86;86 11:43:45 INFO - PROCESS | 1647 | |6;imaging-darkroom;103;151;109;94;94;94;94;95;124;106 11:43:45 INFO - PROCESS | 1647 | |7;imaging-desaturate;84;85;85;86;85;83;91;84;85;84 11:43:45 INFO - PROCESS | 1647 | |8;json-parse-financial;59;58;60;63;65;67;65;65;66;65 11:43:45 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;55;64;65;64;59;58;59;58;58;58 11:43:45 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;86;85;83;83;85;83;81;79;89;100 11:43:45 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;138;136;136;135;135;139;139;138;254;154 11:43:45 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;163;163;166;165;164;165;170;225;217;186 11:43:45 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;65;75;81;82;81;70;65;63;64;65 11:43:45 INFO - PROCESS | 1647 | __end_tp_report 11:43:45 INFO - PROCESS | 1647 | __start_cc_report 11:43:45 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,2114 11:43:45 INFO - PROCESS | 1647 | __end_cc_report 11:43:45 INFO - PROCESS | 1647 | __startTimestamp1495824225386__endTimestamp 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | ------- Summary: start ------- 11:43:45 INFO - PROCESS | 1647 | Number of tests: 14 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:3.38 (2.9%) stddev-sans-first:1.36 11:43:45 INFO - PROCESS | 1647 | Values: 105.0 116.0 117.0 115.0 115.0 114.0 112.0 115.0 115.0 115.0 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:119.80 Median:117.00 stddev:13.58 (11.6%) stddev-sans-first:11.79 11:43:45 INFO - PROCESS | 1647 | Values: 142.0 121.0 110.0 143.0 112.0 113.0 112.0 108.0 129.0 108.0 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:185.10 Median:185.00 stddev:1.10 (0.6%) stddev-sans-first:1.17 11:43:45 INFO - PROCESS | 1647 | Values: 185.0 184.0 185.0 187.0 185.0 186.0 185.0 186.0 185.0 183.0 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:66.70 Median:62.50 stddev:11.12 (17.8%) stddev-sans-first:10.11 11:43:45 INFO - PROCESS | 1647 | Values: 83.0 58.0 60.0 89.0 63.0 61.0 62.0 58.0 73.0 60.0 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:75.10 Median:76.00 stddev:7.81 (10.3%) stddev-sans-first:7.42 11:43:45 INFO - PROCESS | 1647 | Values: 85.0 69.0 87.0 70.0 70.0 69.0 82.0 68.0 69.0 82.0 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:92.80 Median:86.00 stddev:16.03 (18.6%) stddev-sans-first:16.97 11:43:45 INFO - PROCESS | 1647 | Values: 90.0 85.0 85.0 86.0 102.0 136.0 86.0 86.0 86.0 86.0 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:106.40 Median:94.00 stddev:18.45 (19.6%) stddev-sans-first:19.52 11:43:45 INFO - PROCESS | 1647 | Values: 103.0 151.0 109.0 94.0 94.0 94.0 94.0 95.0 124.0 106.0 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.00 stddev:2.20 (2.6%) stddev-sans-first:2.29 11:43:45 INFO - PROCESS | 1647 | Values: 84.0 85.0 85.0 86.0 85.0 83.0 91.0 84.0 85.0 84.0 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:65.00 stddev:3.16 (4.9%) stddev-sans-first:2.95 11:43:45 INFO - PROCESS | 1647 | Values: 59.0 58.0 60.0 63.0 65.0 67.0 65.0 65.0 66.0 65.0 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:59.00 stddev:3.33 (5.6%) stddev-sans-first:3.04 11:43:45 INFO - PROCESS | 1647 | Values: 55.0 64.0 65.0 64.0 59.0 58.0 59.0 58.0 58.0 58.0 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:84.00 stddev:5.82 (6.9%) stddev-sans-first:6.16 11:43:45 INFO - PROCESS | 1647 | Values: 86.0 85.0 83.0 83.0 85.0 83.0 81.0 79.0 89.0 100.0 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:138.50 stddev:36.82 (26.6%) stddev-sans-first:38.78 11:43:45 INFO - PROCESS | 1647 | Values: 138.0 136.0 136.0 135.0 135.0 139.0 139.0 138.0 254.0 154.0 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.40 Median:168.00 stddev:23.53 (14.0%) stddev-sans-first:24.29 11:43:45 INFO - PROCESS | 1647 | Values: 163.0 163.0 166.0 165.0 164.0 165.0 170.0 225.0 217.0 186.0 11:43:45 INFO - PROCESS | 1647 | 11:43:45 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:72.50 stddev:7.88 (10.9%) stddev-sans-first:8.04 11:43:45 INFO - PROCESS | 1647 | Values: 65.0 75.0 81.0 82.0 81.0 70.0 65.0 63.0 64.0 65.0 11:43:45 INFO - PROCESS | 1647 | -------- Summary: end -------- 11:43:45 INFO - PROCESS | 1647 | 11:43:46 INFO - TEST-INFO | 1647: exit 0 11:43:46 INFO - TEST-OK | kraken | took 59278ms 11:43:46 INFO - SUITE-END | took 1177s 11:43:46 INFO - Completed test suite (00:19:37) 11:43:46 INFO - javascript benchmark 11:43:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7708.245318794696, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3405.3700065487883, 3418.8632454701806, 3432.4508078707413, 3466.907924003937, 3476.0, 4805.855824325267, 5497.697626630534, 5705.115707065406, 5829.912551311723, 5859.531237500995, 5784.537237021039, 5832.591718579701, 5851.444112809287, 5874.853128671785, 5877.70611469427, 5301.5228629423345, 5324.5740340772645, 5330.28041214435, 5330.973345133279, 5380.488853558905, 5215.322008138936, 5222.791088356461, 5262.578993680516, 5391.56867450603, 5399.0, 5302.204669299597, 5317.9202311965155, 5318.680879147263, 5324.361076670785, 5353.223782551528, 5388.191771234307, 5401.567874570025, 5401.864953376158, 5403.864903377408, 5413.323334583177, 5149.356330458692, 5240.161574148118, 5309.920351194735, 5313.326485751578, 5359.785608575652, 5733.885322293572, 5751.568632352549, 5760.711964401763, 5769.278840144982, 5770.0, 6149.169862068608, 6233.0, 6291.5281353898645, 6300.527460440485, 6323.494120470351, 4728.385309909724, 6335.176427064498, 6336.271328797156, 6345.302016778151, 6350.618962862197, 5980.491658209014, 6050.092486127072, 6061.999999999956, 6064.575479716377, 6072.635641861502, 6211.503079753609, 6258.780942666985, 6294.716737746812, 6311.400416960381, 6349.55553111287, 5286.524212820787, 6321.1150438938585, 6328.525360597973, 6333.524985626096, 6336.809895703136, 6270.49836013127, 6276.152719382825, 6319.936800631935, 6323.936760632427, 6334.986402175629, 6165.383461653799, 6204.81385558434, 6268.59254148488, 6280.309165991737, 6300.0, 6295.276043255084, 6314.0844577536, 6326.430621244018, 6327.462165715873, 6336.58812177207, 4878.95121048792, 4883.438404583448, 4884.998575292057, 4893.363862697897, 4907.558319751168, 4227.556106608762, 4291.29193683045, 4314.56854314566, 4369.191699535596, 4393.868183954422, 5862.0, 5872.970635146797, 5890.793822216202, 5897.2333596633025, 5920.378360272195, 6292.748290068346, 6318.462930650854, 6320.62076275425, 6334.746610135635, 6339.207599050119, 5843.883122337529, 5855.473007429352, 5859.2382990211, 5862.003459411911, 5886.558508111909, 5308.362996440374, 5412.269343638637, 5462.644928079662, 5489.231507588941, 5520.613557051048, 6435.195600549931, 6475.935240647722, 6487.351264873475, 6487.870242595027, 6507.023946407982, 6296.275928268308, 6296.559240853279, 6304.65324407162, 6305.96847015762, 6306.495480361468, 6255.499560035278, 6403.167588213502, 6490.545661803629, 6492.967535162294, 6497.220333560003, 6246.875062498633, 6248.468880145057, 6254.843628909313, 6256.092866534418, 6265.874682506415, 6142.468080853617, 6234.127222188807, 6278.560500764903, 6279.529035322278, 6302.275238347465, 6237.812865614039, 6300.495960323255, 6302.243730752339, 6326.2092238470195, 6327.050942358683, 6260.968695156495, 6305.5901366411035, 6311.96844015777, 6318.115463834974, 6338.556301058881, 5959.314678811818, 5991.101334799815, 6072.969635151796, 6126.309589765887, 6126.681223142682, 6266.154069200782, 6272.40412160844, 6274.560780745486, 6274.905876411949, 6275.937240627718, 6038.973374526253, 6104.114903338902, 6162.969185154046, 6176.969115154396, 6202.937970620236], "value": 5784.250183793414, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.5600625583986, 783.5925318834206, 837.5686521441456, 892.6875593542261, 907.5825120444595, 28470.14589562311, 41627.79186104069, 41694.16611667765, 41696.37455438163, 41825.581744182586, 42867.99924005321, 42881.57118428815, 42902.57097429025, 42958.35562466565, 43217.351739723854, 7634.847303053935, 9591.520423978807, 9743.658971935984, 9757.365771224882, 9758.512074396287, 7707.537547747125, 7803.882941755878, 7816.648250828726, 7820.687172513092, 7821.374290056782, 11771.411429428537, 12026.15816892818, 12181.269123852553, 12193.146479746378, 12216.450259738289, 11891.04871610269, 11924.642260732191, 11942.343171125542, 11974.64076077719, 12007.519699212022, 11516.654500365003, 11596.942015289955, 11618.941905290503, 11621.825672614917, 11626.069914406828, 40230.99422514431, 40331.19337613261, 40416.595834041575, 40482.595174048176, 40536.594634053574, 3919.8432062717457, 3930.253251882133, 3942.763434193929, 3983.601639836022, 3991.560928297885, 27066.86466567674, 27900.720992790015, 29625.70374296251, 29794.10617681473, 30004.0, 3060.3573249617607, 3159.7788154829173, 3299.835008249578, 3307.834608269577, 3310.7351411886993, 341660.0, 343938.28030859935, 346099.0, 346497.0, 355006.0, 53274.0, 53501.0, 53554.46445535573, 53574.73212633912, 53637.927241454956, 55640.72179639076, 55661.44338556644, 55698.16452753212, 55719.721401393545, 55727.164092538645, 14716.779248311284, 15091.0, 15116.470923517623, 15124.773128403083, 15145.394184232728, 12777.425015874307, 12814.35928203586, 12818.0, 12844.100912936192, 12858.357082145856, 10605.257631965842, 10807.67576972692, 11968.880311196777, 12470.376481175905, 12524.373781310898, 4513.638908887281, 4708.529147085264, 5203.453637368022, 5373.919391209135, 5423.918641220384, 1041.182671602788, 1110.3448965110624, 1190.6070996571111, 1192.6899006258436, 1226.0804396702474, 27231.455370892472, 27507.0, 27640.0, 27704.445911081664, 27796.86101569479, 1137.5051852444212, 1196.3958201108385, 1276.6955645060755, 1319.4326439630859, 1322.3916998180753, 13469.057165998482, 13906.235157066256, 14232.573022809125, 14233.572992810232, 14616.415343386358, 482922.0, 498993.0, 500180.0, 614770.0, 616621.0, 41050.589494105276, 41570.79214603908, 48148.0, 48439.78900527445, 48530.02939941181, 1248.6628610275175, 1426.1015560197218, 1436.346462359624, 1454.6072560408843, 1455.2432734977751, 23310.88344558266, 26622.06822761194, 26999.730002700508, 27015.324616884736, 27971.720282796912, 40497.392539111344, 40905.79547102245, 40947.0, 40960.97597560085, 41201.0, 367695.0, 369164.0, 369451.0, 370037.0, 497591.0, 24688.629670554597, 32002.19994500156, 38842.6115738839, 39465.60534394619, 39525.20949580935, 45901.31148032849, 46512.0, 47785.04429911452, 48107.75946120247, 48418.273725893436, 31497.8425107873, 32631.510527341634, 32847.67152328446, 33821.32357352888, 33999.49000764941, 27496.58755118715, 30714.0, 32193.034208973768, 32481.02556923296, 33039.00882973515, 38814.80592597019, 39660.2067958645, 39739.80130099447, 39758.40362394509, 39766.20467590574, 33097.338053238316, 33739.0, 34010.82994585011, 34575.30849383049, 34607.0, 24546.263612091665, 25883.741162588885, 25991.870040650432, 26227.868860655573, 26539.0], "value": 19144.31450701093, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.8864562245383, 432.6495538613721, 436.670791310538, 439.83286351186547, 450.30877602879565, 18117.547061323472, 19872.801271987293, 20735.170593176274, 22050.0, 22155.778442215593, 21777.0, 22469.21357752479, 22675.09299628017, 22992.965316560752, 23141.190058347962, 6461.450776683988, 6765.0867132937165, 6783.152105986739, 6814.250432452426, 7008.159020917472, 6773.898391524106, 6880.965595172017, 6941.305869413069, 6951.687174077153, 6954.30456954303, 6586.407223349897, 6776.69504872282, 6873.690683919235, 6952.652367381636, 6957.5129740918155, 6491.091247225392, 6760.0, 6779.62712050837, 6809.0, 6827.2831352708, 6604.075429439882, 6613.37172968572, 6674.833129171761, 6682.398584127452, 6697.93302066978, 27366.726332736616, 27707.861460692566, 27748.86125569359, 27751.028713994914, 28148.577771333446, 3046.512557990733, 3064.4024415239037, 3066.861991210401, 3069.815811051344, 3081.6610172880964, 15597.376104955789, 16212.75680864788, 16353.0, 17556.473305800846, 17929.551761205945, 1917.338518211215, 2054.3734161080865, 2096.622607930572, 2109.1457959526415, 2114.4819519217735, 135096.0, 137217.0, 143159.2842035783, 144128.99999999895, 146118.0, 17543.21055552503, 19310.80689193118, 20044.298449554197, 20430.18279268842, 20507.28224512165, 26242.343941401235, 31387.372252554822, 31647.36705265928, 32061.67938320587, 32112.518312225337, 8810.911890881009, 10137.797244055078, 10191.337563058378, 10456.163506919429, 10491.213159013105, 8685.782855428539, 8695.956520217358, 8709.604858663919, 8805.0, 8840.33697472692, 9404.0, 9497.810043799223, 9540.045995400404, 9550.856737148948, 9578.808423831484, 3606.314800187956, 4012.8563359443, 4041.1917616476235, 4097.426360309559, 4126.607972242632, 974.5030034682221, 1028.1928685981468, 1028.3624153025173, 1043.3218408034838, 1048.3814549415881, 20974.790252097588, 21373.358799236048, 21425.571488570455, 21457.570848582942, 21486.462838429165, 1031.5718976624767, 1034.4672493665769, 1048.0, 1050.6847945616285, 1064.6593090211059, 9390.624375024923, 10654.73363165927, 11105.888941110647, 11239.0, 11258.268212566158, 770263.0, 787130.0, 787905.0, 790441.0477947573, 809969.0, 134599.0, 135132.0, 137205.62794372128, 141028.2948585271, 154337.0, 1196.4795314038422, 1202.6512311429587, 1226.9509219631293, 1229.3423018684782, 1231.735176936963, 17768.0, 18607.348742793936, 18727.438176854717, 18774.0, 18803.05984700787, 61976.0, 62490.375096250275, 62696.686516567126, 63101.0, 63867.68066159826, 86707.1329286699, 87176.0, 88018.55990720005, 89428.0, 90428.64357034519, 9541.47521886303, 11004.944975275072, 11510.48202830858, 11633.185677002528, 11792.76414471723, 13264.46942122343, 13335.533256335983, 13359.0, 13755.243461609307, 13784.93107534496, 11132.721681958015, 11305.830412543983, 11588.826167607662, 11739.47172377228, 11819.468123934616, 10700.464976751287, 10833.29583577065, 10931.125509959025, 11026.724331891446, 11121.83317250258, 11158.832617510581, 11683.474243659226, 11896.464659090534, 11958.820617690915, 12106.636800895987, 10280.640177593748, 11189.328640281608, 11509.079273658255, 11796.823047654463, 11830.112741544246, 11295.774084518427, 11379.870535602775, 11437.313761174024, 11605.0, 11652.708682282671], "value": 11636.954374214405, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.3309878159705, 1587.54754894855, 1611.0655819624612, 1612.1052815687297, 1633.3956436118633, 9227.07729227077, 9675.758106047353, 10901.018908298262, 10957.287776294535, 11383.0, 11048.779024419528, 11174.0, 11175.664730058112, 11178.32930024199, 11213.495392707327, 1997.530580313625, 2361.2325994051916, 2392.306231192952, 2407.205622144697, 2409.1567951217107, 2086.1655337864822, 2253.290213582716, 2348.7064116985375, 2358.41039740065, 2359.3393849722106, 2368.7749663782, 2387.85672859629, 2390.438247011951, 2396.772306630867, 2398.1846172301503, 2343.215022967308, 2349.0721165139676, 2351.0595761695286, 2354.776296251853, 2355.4935688826986, 1932.4492519631979, 2308.157522504274, 2387.85672859629, 2388.199953015742, 2389.0802041214256, 11217.934296241843, 11297.98318151362, 11313.264637798598, 11319.151063670257, 11325.980661740401, 5592.217089607459, 5752.309722833245, 5764.625299355528, 5765.365809760923, 5780.479756821865, 3295.2001989554865, 3378.6283508814013, 3524.577050753926, 3586.8923932282073, 3587.6950459211, 1123.6516679829278, 1181.362064485177, 1196.7785959597502, 1196.8803119688048, 1214.8056310990198, 15409.768853467207, 15573.999999999887, 15579.98730082541, 15584.922075389664, 15593.844061559352, 4738.740626543495, 4747.765580948978, 4805.783739731721, 4862.659613826998, 4914.0, 12973.962083033335, 12979.610611681665, 12995.28525931064, 13005.414756335987, 13005.544805931937, 5712.857428514314, 5812.3025236971835, 5825.679587622722, 5830.387809279964, 5842.474177324061, 4400.471943366816, 4951.628627852925, 5148.6910785353, 5169.069567477815, 5267.525922666979, 3763.8682646107254, 3844.692424606025, 3856.9614303857165, 3877.515310586177, 3902.765834049966, 1003.1072345612411, 1004.2618675273814, 1015.8577799108131, 1018.8267994440963, 1026.4200726589602, 951.790606066678, 971.9271054670928, 979.1872745621117, 980.529345913951, 1000.2448151645461, 6701.664916754143, 6895.172579290517, 6896.793096207121, 6917.688704008332, 6944.930550694529, 981.1513041219293, 1011.3540685827633, 1017.353980222564, 1018.0938964321759, 1020.9081182693593, 3647.7446578739764, 4155.9194609401775, 4231.132617813341, 4406.206882761134, 4435.556444355539, 11494.448249486213, 13428.798568021488, 13682.973776966768, 13855.37650805696, 13865.722685546232, 13764.17414955106, 13767.0, 13780.793288100687, 13785.035047546575, 13800.0, 8624.69813556535, 9854.605815767432, 10074.44590547527, 10324.225683073799, 10336.224783141295, 6639.269680335063, 6729.1252137221845, 6754.932450675627, 6763.52655314143, 6810.046593476818, 11983.580574679845, 12039.939800300943, 12355.196912200678, 12373.876261237272, 12375.195612285534, 10687.839682404614, 12286.754264914473, 12395.256284622952, 12505.562305319634, 12563.811542827048, 7503.399728021855, 8672.089430609947, 8734.301255899385, 8749.518776467356, 8763.123687631185, 10264.948675256575, 10456.424896630455, 10532.21008424356, 10547.472626368804, 10548.894511054792, 8638.91361086381, 8778.51718155482, 8864.072935017532, 8991.82016359656, 9313.022132675971, 8641.438306510057, 8663.26362259215, 8703.042665306684, 8727.738167854974, 8772.298216142566, 8007.119216886254, 8165.632546535539, 8167.795805104683, 8179.222973817716, 8187.79530511742, 5957.940420595739, 5998.850028749316, 6003.219581454558, 6152.876942461216, 6195.628262304276, 5996.0, 6031.547633927561, 6173.629582225081, 6246.156768836149, 6380.234371875219], "value": 5136.206536150155, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.31344266876954, 386.88393481955416, 390.0288282177379, 392.8035982008994, 399.69623086454294, 5004.099262132815, 5041.621878359119, 5048.368953880769, 5124.565831230164, 5185.470286265551, 4102.774347410891, 4943.752812359386, 5017.444592176428, 5034.09386310464, 5048.217526283423, 4115.73247738896, 4184.623383895449, 4234.767087810169, 4241.554636763141, 4248.97875510622, 3660.2313514162056, 3960.980195099035, 4044.4944381952255, 4202.285611446062, 4243.790519701885, 4236.597523235288, 4302.204092242945, 4317.330813723862, 4335.024619460612, 4347.2826983547675, 3404.931901361959, 3713.9119406413115, 4283.100548884722, 4309.719868208556, 4317.287647538168, 3429.216243340551, 3870.845166193377, 3890.513685789276, 3894.55212650546, 3900.7074469414906, 4628.398308219944, 4788.377510923592, 4869.805207791684, 5033.0, 5059.392872855281, 4495.34817451471, 4589.518100599423, 4932.090066860534, 4939.431965323962, 4967.304577359137, 4763.21379482694, 5037.974810125926, 5184.403793563751, 5409.675419474805, 5436.0, 3756.9624303756887, 3958.832933653066, 4100.5899410059055, 4301.65586753059, 4365.49796773372, 4846.39420072491, 5487.945120548783, 5606.4307589314985, 5715.685637289947, 5795.391483894173, 3661.7802931823644, 3790.753601015925, 3960.465337179444, 4158.771267580312, 4282.0, 3918.49059622253, 4469.664775141878, 4471.9043834260165, 4481.484629267611, 4510.421352526655, 4346.326319420495, 4410.059573787523, 4737.8341758038305, 4839.225723884161, 4846.636502262344, 4994.776279811469, 5288.7091209983055, 5427.83716488506, 5457.617966742369, 5538.473844984801, 1377.7657798174334, 1433.9317208679392, 1487.6355292953297, 1499.3777582303221, 1530.9540713778604, 1090.3185509056839, 1108.728361551425, 1143.4797167288864, 1160.118310084325, 1177.9823302650468, 4436.534163912743, 4460.487043989918, 4801.591864691471, 4894.510548945148, 4897.111742187917, 5664.97167514168, 5681.943180568224, 5682.54539636828, 5714.7999820006935, 5773.39379365169, 4203.8031644650655, 4330.566943305707, 4413.668974826901, 4445.621857224271, 4758.262469317261, 2626.755956393852, 2636.8417894926365, 2771.5426954552677, 2879.4960881845595, 2880.9279768005968, 5140.203268493389, 5369.5167434931045, 5761.107028410603, 5848.678322692293, 5861.912071318934, 5043.899122017539, 5208.947910520847, 5380.757865896044, 5592.720363981784, 5708.257926469615, 5555.250041244381, 5565.387807341189, 5632.7746890123935, 5723.713814309268, 5739.9139012915675, 4961.70229786214, 5135.666181698178, 5218.0346635870965, 5550.75021624036, 5611.727250463568, 4824.140687450096, 4900.926486102782, 5131.666441681279, 5327.733613319241, 5525.143602741581, 5019.4690619361445, 5602.4957753802355, 5673.319201695823, 5751.798687045934, 5767.884642307046, 3953.4465174874967, 4088.672906167559, 4390.736555806662, 4391.8462853799965, 4392.428984232157, 5130.8717282066755, 5439.062906030699, 5461.095316848314, 5541.667499950016, 5588.944110558842, 5112.74436278192, 5354.3842458117815, 5361.35663720356, 5411.375482890873, 5431.511163995259, 4619.607333376701, 4700.882477937942, 4845.2449351752675, 5111.821086261963, 5155.458676839028, 4664.3441578239235, 4692.178868698033, 5218.295530103387, 5309.283246761638, 5389.784408623611, 4782.497837726988, 4920.434150072644, 5159.871003224949, 5505.532029777513, 5529.834104976857, 4878.219484882402, 5021.598272138147, 5170.594269838908, 5258.793794143694, 5422.945770542402], "value": 4123.397571433924, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.388455639541, 769.9692012319507, 780.5121798875703, 846.2595229174473, 854.0520022774721, 13704.0, 15532.767008494882, 15801.209939503022, 16534.503964881056, 16817.83182168178, 14837.0, 15202.847971520308, 15225.0, 15358.0, 15371.538853834374, 1000.1398797034544, 1027.3579013116803, 1028.1466382902174, 1029.0224286927412, 1029.953652085658, 839.5340585974782, 870.3994243971671, 1030.4950574218653, 1034.3483605328634, 1034.7361422837166, 1069.8341757027674, 1071.3893080943865, 1072.7210925159475, 1073.8657667791526, 1074.1084899533369, 1063.0220197418394, 1063.1547919404093, 1063.457636605333, 1065.4938904020605, 1065.5737704918017, 795.5783434779576, 795.8806179073127, 796.2952786783738, 799.3605115907309, 799.3724925933114, 16305.0, 16323.0, 16342.836571634369, 17168.656626867392, 17488.825111748847, 13483.932580337134, 13509.121907076009, 13722.0, 13728.519501817489, 13764.65588360289, 11835.845846292721, 11892.405379730979, 11916.34460104694, 11946.581869634609, 12128.272303661808, 3703.407454807218, 3708.388115960863, 3716.7955762433057, 3724.3854763963914, 3733.5146430964073, 83801.0, 109994.0, 117444.41277793642, 119022.0, 121174.0, 11090.611828585961, 11147.777044459066, 11218.102551795837, 11312.151588630886, 11381.886181138083, 20711.58576828485, 23704.288871333887, 23831.880840595684, 24005.51988960211, 24084.0, 7921.247481489249, 7938.444308898322, 8023.584080745473, 8037.477563958324, 8071.757847264592, 4330.783460826946, 4355.651547876162, 4372.431583894074, 4514.074614703978, 4648.814047438133, 3480.756347055682, 3566.5720113586535, 3579.6957258633306, 3580.874669386611, 3586.4082426399873, 509.0648876569878, 624.0358645892107, 630.7224821078711, 631.857831987799, 632.5350867112669, 677.8881484554993, 694.9166100067923, 700.352174238827, 710.5807573531641, 719.9352058314777, 9178.31162662803, 9818.558164882597, 9914.851277230848, 10526.368417894952, 10621.044106030495, 650.4016304999412, 655.6983787457823, 706.6502081469705, 715.4777012780595, 723.565860483716, 4487.192305385062, 4973.154563724175, 4980.750962451935, 4992.875178120504, 5032.949670503322, 67229.66385168042, 89838.0, 90405.0, 91438.08561914296, 93135.53432232795, 61488.0, 73477.0, 74166.6291668549, 83475.0, 84031.31937361219, 51938.0, 72419.27580724125, 74301.62849185719, 75105.0, 75644.62177689077, 10801.783964320826, 11148.335377344458, 11167.441627918734, 11233.887661123612, 11253.549858005588, 46331.76834115808, 48726.78183045452, 48889.0, 49095.0, 50334.49665503445, 61836.0, 62302.37697622966, 62364.37635623586, 63060.36939630729, 64651.0, 12898.935505322414, 13009.934950325567, 13249.735005299648, 13750.037497375088, 13917.164970101827, 14825.481108161162, 15068.246587670354, 15305.846941530443, 15410.152441615819, 16117.194140293173, 13732.734098873574, 14034.0, 14117.152970821784, 14217.360218790385, 14224.431022758974, 13796.379162937492, 14251.0, 14275.42898284057, 14338.784918226027, 14599.781003285172, 11189.888101118884, 13563.660908477366, 13716.519921802688, 13830.37763300674, 13864.306784660928, 8059.597020148852, 9224.354295199271, 9278.72163835086, 9449.0, 9788.216942644714, 10268.58925642966, 10503.42231177262, 11055.166808730248, 11391.0, 11405.486753096297], "value": 7685.388744477138, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1453.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 117.0, 115.0, 115.0, 114.0, 112.0, 115.0, 115.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 121.0, 110.0, 143.0, 112.0, 113.0, 112.0, 108.0, 129.0, 108.0], "value": 119.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 185.0, 187.0, 185.0, 186.0, 185.0, 186.0, 185.0, 183.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 60.0, 89.0, 63.0, 61.0, 62.0, 58.0, 73.0, 60.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 69.0, 87.0, 70.0, 70.0, 69.0, 82.0, 68.0, 69.0, 82.0], "value": 75.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 85.0, 86.0, 102.0, 136.0, 86.0, 86.0, 86.0, 86.0], "value": 92.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 151.0, 109.0, 94.0, 94.0, 94.0, 94.0, 95.0, 124.0, 106.0], "value": 106.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 86.0, 85.0, 83.0, 91.0, 84.0, 85.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 60.0, 63.0, 65.0, 67.0, 65.0, 65.0, 66.0, 65.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 64.0, 65.0, 64.0, 59.0, 58.0, 59.0, 58.0, 58.0, 58.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 83.0, 83.0, 85.0, 83.0, 81.0, 79.0, 89.0, 100.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 136.0, 135.0, 135.0, 139.0, 139.0, 138.0, 254.0, 154.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 166.0, 165.0, 164.0, 165.0, 170.0, 225.0, 217.0, 186.0], "value": 178.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 81.0, 82.0, 81.0, 70.0, 65.0, 63.0, 64.0, 65.0], "value": 71.1, "unit": "score"}]}]} 11:43:46 INFO - Return code: 0 11:43:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:43:46 INFO - # TBPL SUCCESS # 11:43:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:43:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:43:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:43:46 INFO - 2017-05-26 11:43:46.710 screenresolution[1651:9742] starting screenresolution argv=screenresolution get 11:43:46 INFO - 2017-05-26 11:43:46.713 screenresolution[1651:9742] Display 0: 1600x1200x32@60 11:43:46 INFO - 2017-05-26 11:43:46.719 screenresolution[1652:9745] starting screenresolution argv=screenresolution list 11:43:46 INFO - Available Modes on Display 0 11:43:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:43:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:43:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:43:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:43:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:43:46 INFO - Intel Iris: 11:43:46 INFO - Chipset Model: Intel Iris 11:43:46 INFO - Type: GPU 11:43:46 INFO - Bus: Built-In 11:43:46 INFO - VRAM (Dynamic, Max): 1536 MB 11:43:46 INFO - Vendor: Intel (0x8086) 11:43:46 INFO - Device ID: 0x0a2e 11:43:46 INFO - Revision ID: 0x0009 11:43:46 INFO - Displays: 11:43:46 INFO - Display: 11:43:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:43:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:43:46 INFO - Display Serial Number: 1600x1200 60 11:43:46 INFO - Main Display: Yes 11:43:46 INFO - Mirror: Off 11:43:46 INFO - Online: Yes 11:43:46 INFO - Rotation: Supported 11:43:46 INFO - Return code: 0 11:43:46 INFO - Running post-action listener: _resource_record_post_action 11:43:46 INFO - [mozharness: 2017-05-26 18:43:46.954079Z] Finished run-tests step (success) 11:43:46 INFO - Running post-run listener: _resource_record_post_run 11:43:47 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 38548480; Write bytes: 509232128; Read time: 244; Write time: 1560 11:43:47 INFO - TinderboxPrint: CPU usage
54.1% 11:43:47 INFO - TinderboxPrint: I/O read bytes / time
38,548,480 / 244 11:43:47 INFO - TinderboxPrint: I/O write bytes / time
509,232,128 / 1,560 11:43:47 INFO - TinderboxPrint: CPU idle
2,202.4 (45.9%) 11:43:47 INFO - TinderboxPrint: CPU system
954.3 (19.9%) 11:43:47 INFO - TinderboxPrint: CPU user
1,636.9 (34.1%) 11:43:47 INFO - TinderboxPrint: Swap in / out
247,074,816 / 0 11:43:47 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208404992; Write bytes: 203573248; Read time: 15819; Write time: 438 11:43:47 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34501632; Write bytes: 295447552; Read time: 220; Write time: 1087 11:43:47 INFO - Running post-run listener: _upload_blobber_files 11:43:47 INFO - Blob upload gear active. 11:43:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:43:47 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 11:43:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:43:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:43:47 INFO - (blobuploader) - INFO - Open directory for files ... 11:43:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:43:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:43:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:49 INFO - (blobuploader) - INFO - Done attempting. 11:43:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:43:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:43:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:49 INFO - (blobuploader) - INFO - Done attempting. 11:43:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:43:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:43:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:50 INFO - (blobuploader) - INFO - Done attempting. 11:43:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:43:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:43:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:52 INFO - (blobuploader) - INFO - Done attempting. 11:43:52 INFO - (blobuploader) - INFO - Iteration through files over. 11:43:52 INFO - Return code: 0 11:43:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:43:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:43:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe8f172530f8177682b8414109ebd7a79b30d49c1ef379ae51ffc8b82a7965b584746a9e11607d2e3e98154c4887ea5cce2d9c3067b0c2d3439f608b84c007b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5070c55b4ccb15612c2261a38eb90cc099380d9379e3fc76a3a75d6be7ff10f649a556df041dca419721ed26f36f26de24949fd62aa26d2a2195763a6612bf6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4f64d9dfe72fec272fadd46432e3f7d7587eae3e0a9fc2f0d72a2318d93e0b258c5a94fd0602d6ad8435f8d791944971f868f6a2e5b17e875663485f9bce6f72", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/869f0320577e07ad55898c26f6f7dda2f1c0e32e8a6bcab6e473f10821ecbdf91b2706afddcb9a6be35322e81a8a1290e2f5e75dd58884fa545a92a7d0ad555e"} 11:43:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:43:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:43:52 INFO - Contents: 11:43:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe8f172530f8177682b8414109ebd7a79b30d49c1ef379ae51ffc8b82a7965b584746a9e11607d2e3e98154c4887ea5cce2d9c3067b0c2d3439f608b84c007b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5070c55b4ccb15612c2261a38eb90cc099380d9379e3fc76a3a75d6be7ff10f649a556df041dca419721ed26f36f26de24949fd62aa26d2a2195763a6612bf6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4f64d9dfe72fec272fadd46432e3f7d7587eae3e0a9fc2f0d72a2318d93e0b258c5a94fd0602d6ad8435f8d791944971f868f6a2e5b17e875663485f9bce6f72", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/869f0320577e07ad55898c26f6f7dda2f1c0e32e8a6bcab6e473f10821ecbdf91b2706afddcb9a6be35322e81a8a1290e2f5e75dd58884fa545a92a7d0ad555e"} 11:43:52 INFO - Running post-run listener: copy_logs_to_upload_dir 11:43:52 INFO - Copying logs to upload dir... 11:43:52 INFO - mkdir: /builds/slave/test/build/upload/logs 11:43:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.324719 ========= master_lag: 0.96 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-26 11:43:53.812565) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 11:43:53.815779) ========= 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.x7H4PguVn5/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.HQCPL0jPez/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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/Mozilla-Beta/sha512/fe8f172530f8177682b8414109ebd7a79b30d49c1ef379ae51ffc8b82a7965b584746a9e11607d2e3e98154c4887ea5cce2d9c3067b0c2d3439f608b84c007b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5070c55b4ccb15612c2261a38eb90cc099380d9379e3fc76a3a75d6be7ff10f649a556df041dca419721ed26f36f26de24949fd62aa26d2a2195763a6612bf6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4f64d9dfe72fec272fadd46432e3f7d7587eae3e0a9fc2f0d72a2318d93e0b258c5a94fd0602d6ad8435f8d791944971f868f6a2e5b17e875663485f9bce6f72", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/869f0320577e07ad55898c26f6f7dda2f1c0e32e8a6bcab6e473f10821ecbdf91b2706afddcb9a6be35322e81a8a1290e2f5e75dd58884fa545a92a7d0ad555e"} build_url:https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011093 build_url: 'https://queue.taskcluster.net/v1/task/IUO70beITL-hYjTHPplKBg/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe8f172530f8177682b8414109ebd7a79b30d49c1ef379ae51ffc8b82a7965b584746a9e11607d2e3e98154c4887ea5cce2d9c3067b0c2d3439f608b84c007b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5070c55b4ccb15612c2261a38eb90cc099380d9379e3fc76a3a75d6be7ff10f649a556df041dca419721ed26f36f26de24949fd62aa26d2a2195763a6612bf6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4f64d9dfe72fec272fadd46432e3f7d7587eae3e0a9fc2f0d72a2318d93e0b258c5a94fd0602d6ad8435f8d791944971f868f6a2e5b17e875663485f9bce6f72", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/869f0320577e07ad55898c26f6f7dda2f1c0e32e8a6bcab6e473f10821ecbdf91b2706afddcb9a6be35322e81a8a1290e2f5e75dd58884fa545a92a7d0ad555e"}' ========= master_lag: 0.28 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 11:43:54.111326) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 11:43:54.111831) ========= 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.x7H4PguVn5/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.HQCPL0jPez/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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.005134 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 11:43:54.147137) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 11:43:54.147566) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 11:43:54.148869) ========= ========= Total master_lag: 1.34 =========