builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0207 starttime: 1495729752.84 results: success (0) buildid: 20170525080444 builduid: a0bf37f597d64b84a24b10f70f0b660e revision: 38c9c1ad194a58d08440919f0c4618993917315b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 09:29:12.843383) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 09:29:12.843802) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 09:29:12.844071) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 09:29:12.869179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:29:12.869531) ========= 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.lanVmrnZc2/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.6iOdqTlBN1/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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-25 09:29:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.7M=0.001s 2017-05-25 09:29:12 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104781 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:29:12.997466) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:29:12.997792) ========= 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.lanVmrnZc2/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.6iOdqTlBN1/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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.026897 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:29:13.048073) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 09:29:13.048684) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 38c9c1ad194a58d08440919f0c4618993917315b --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 38c9c1ad194a58d08440919f0c4618993917315b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lanVmrnZc2/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.6iOdqTlBN1/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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-25 09:29:13,142 truncating revision to first 12 chars 2017-05-25 09:29:13,142 Setting DEBUG logging. 2017-05-25 09:29:13,142 attempt 1/10 2017-05-25 09:29:13,142 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/38c9c1ad194a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 09:29:14,272 unpacking tar archive at: mozilla-beta-38c9c1ad194a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.404175 ========= master_lag: 0.18 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 09:29:14.637337) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 09:29:14.637668) ========= script_repo_revision: 38c9c1ad194a58d08440919f0c4618993917315b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 09:29:14.638043) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 09:29:14.638306) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 09:29:14.657832) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-25 09:29:14.658203) ========= /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.lanVmrnZc2/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.6iOdqTlBN1/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 09:29:14 INFO - MultiFileLogger online at 20170525 09:29:14 in /builds/slave/test 09:29:14 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 09:29:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:29:14 INFO - {'append_to_log': False, 09:29:14 INFO - 'base_work_dir': '/builds/slave/test', 09:29:14 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:29:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:29:14 INFO - 'branch': 'Mozilla-Beta', 09:29:14 INFO - 'buildbot_json_path': 'buildprops.json', 09:29:14 INFO - 'config_files': ('talos/mac_config.py',), 09:29:14 INFO - 'default_actions': ('clobber', 09:29:14 INFO - 'read-buildbot-config', 09:29:14 INFO - 'download-and-extract', 09:29:14 INFO - 'populate-webroot', 09:29:14 INFO - 'create-virtualenv', 09:29:14 INFO - 'install', 09:29:14 INFO - 'run-tests'), 09:29:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:29:14 INFO - 'download_minidump_stackwalk': True, 09:29:14 INFO - 'download_symbols': 'ondemand', 09:29:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:29:14 INFO - 'tooltool.py': '/tools/tooltool.py', 09:29:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:29:14 INFO - '/tools/misc-python/virtualenv.py')}, 09:29:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:29:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:29:14 INFO - 'gecko_profile': False, 09:29:14 INFO - 'gecko_profile_interval': 0, 09:29:14 INFO - 'installer_path': 'installer.exe', 09:29:14 INFO - 'log_level': 'info', 09:29:14 INFO - 'log_name': 'talos', 09:29:14 INFO - 'log_to_console': True, 09:29:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:29:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:29:14 INFO - 'opt_config_files': (), 09:29:14 INFO - 'pip_index': False, 09:29:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:29:14 INFO - 'cmd': ('bash', 09:29:14 INFO - '-c', 09:29:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:29:14 INFO - 'enabled': True, 09:29:14 INFO - 'halt_on_failure': False, 09:29:14 INFO - 'name': 'check_screen_resolution'},), 09:29:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:29:14 INFO - 'cmd': ('bash', 09:29:14 INFO - '-c', 09:29:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:29:14 INFO - 'enabled': True, 09:29:14 INFO - 'halt_on_failure': False, 09:29:14 INFO - 'name': 'check_screen_resolution'},), 09:29:14 INFO - 'run_cmd_checks_enabled': True, 09:29:14 INFO - 'suite': 'dromaeojs', 09:29:14 INFO - 'system_bits': '32', 09:29:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:29:14 INFO - 'title': 't-yosemite-r7-0207', 09:29:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:29:14 INFO - 'use_talos_json': True, 09:29:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:29:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:29:14 INFO - 'work_dir': 'build'} 09:29:14 INFO - [mozharness: 2017-05-25 16:29:14.858421Z] Running clobber step. 09:29:14 INFO - Running pre-action listener: _resource_record_pre_action 09:29:14 INFO - Running main action method: clobber 09:29:14 INFO - rmtree: /builds/slave/test/build 09:29:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:29:17 INFO - Running post-action listener: _resource_record_post_action 09:29:17 INFO - [mozharness: 2017-05-25 16:29:17.211152Z] Finished clobber step (success) 09:29:17 INFO - [mozharness: 2017-05-25 16:29:17.211285Z] Running read-buildbot-config step. 09:29:17 INFO - Running pre-action listener: _resource_record_pre_action 09:29:17 INFO - Running main action method: read_buildbot_config 09:29:17 INFO - Using buildbot properties: 09:29:17 INFO - { 09:29:17 INFO - "project": "", 09:29:17 INFO - "product": "firefox", 09:29:17 INFO - "script_repo_revision": "production", 09:29:17 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 09:29:17 INFO - "repository": "", 09:29:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 09:29:17 INFO - "buildid": "20170525080444", 09:29:17 INFO - "pgo_build": "False", 09:29:17 INFO - "basedir": "/builds/slave/test", 09:29:17 INFO - "buildnumber": 5, 09:29:17 INFO - "slavename": "t-yosemite-r7-0207", 09:29:17 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:29:17 INFO - "platform": "yosemite_r7_devedition", 09:29:17 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 09:29:17 INFO - "branch": "mozilla-beta", 09:29:17 INFO - "repo_path": "releases/mozilla-beta", 09:29:17 INFO - "revision": "38c9c1ad194a58d08440919f0c4618993917315b", 09:29:17 INFO - "stage_platform": "macosx64-devedition", 09:29:17 INFO - "builduid": "a0bf37f597d64b84a24b10f70f0b660e", 09:29:17 INFO - "slavebuilddir": "test" 09:29:17 INFO - } 09:29:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 09:29:17 INFO - Running post-action listener: _resource_record_post_action 09:29:17 INFO - [mozharness: 2017-05-25 16:29:17.214624Z] Finished read-buildbot-config step (success) 09:29:17 INFO - [mozharness: 2017-05-25 16:29:17.214766Z] Running download-and-extract step. 09:29:17 INFO - Running pre-action listener: _resource_record_pre_action 09:29:17 INFO - Running main action method: download_and_extract 09:29:17 INFO - mkdir: /builds/slave/test/build/tests 09:29:17 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')]} 09:29:17 INFO - trying https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 09:29:17 INFO - Downloading https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/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 09:29:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/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 09:29:18 INFO - Downloaded 1287 bytes. 09:29:18 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:29:18 INFO - Using the following test package requirements: 09:29:18 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 09:29:18 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:29:18 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 09:29:18 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 09:29:18 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:29:18 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 09:29:18 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:29:18 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 09:29:18 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 09:29:18 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 09:29:18 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 09:29:18 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 09:29:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:29:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:29:18 INFO - Fetch https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:29:20 INFO - Expected file size: 51963969 09:29:20 INFO - Obtained file size: 51963969 09:29:36 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 09:29:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:29:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:29:36 INFO - Fetch https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:29:38 INFO - Expected file size: 51963969 09:29:38 INFO - Obtained file size: 51963969 09:29:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 09:29:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 09:29:53 INFO - Fetch https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 09:29:53 INFO - Expected file size: 14404449 09:29:53 INFO - Obtained file size: 14404449 09:29:54 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')]} 09:29:54 INFO - trying https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:29:54 INFO - Downloading https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 09:29:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/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 09:29:56 INFO - Downloaded 74895811 bytes. 09:29:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:29:56 INFO - mkdir: /builds/slave/test/properties 09:29:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:29:56 INFO - Writing to file /builds/slave/test/properties/build_url 09:29:56 INFO - Contents: 09:29:56 INFO - build_url:https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:29:56 INFO - Running post-action listener: _resource_record_post_action 09:29:56 INFO - Running post-action listener: set_extra_try_arguments 09:29:56 INFO - [mozharness: 2017-05-25 16:29:56.903916Z] Finished download-and-extract step (success) 09:29:56 INFO - [mozharness: 2017-05-25 16:29:56.904075Z] Running populate-webroot step. 09:29:56 INFO - Running pre-action listener: _resource_record_pre_action 09:29:56 INFO - Running main action method: populate_webroot 09:29:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:29:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:29:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:29:56 INFO - '--tppagecycles', 09:29:56 INFO - '7'], 09:29:56 INFO - 'tests': ['tsvgm']}}, 09:29:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:29:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:29:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:29:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:29:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:29:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:29:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:29:56 INFO - 'talos_options': ['--disable-e10s'], 09:29:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:29:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:29:56 INFO - 'talos_options': ['--disable-e10s'], 09:29:56 INFO - 'tests': ['damp', 'tps']}, 09:29:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:29:56 INFO - 'tests': ['damp', 'tps']}, 09:29:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:29:56 INFO - 'tests': ['dromaeo_dom']}, 09:29:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:29:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:29:56 INFO - 'tests': ['basic_compositor_video']}, 09:29:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:29:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:29:56 INFO - 'tests': ['a11yr', 09:29:56 INFO - 'ts_paint', 09:29:56 INFO - 'tpaint', 09:29:56 INFO - 'sessionrestore', 09:29:56 INFO - 'sessionrestore_no_auto_restore', 09:29:56 INFO - 'tabpaint']}, 09:29:56 INFO - 'other-e10s': {'tests': ['a11yr', 09:29:56 INFO - 'ts_paint', 09:29:56 INFO - 'tpaint', 09:29:56 INFO - 'sessionrestore', 09:29:56 INFO - 'sessionrestore_no_auto_restore', 09:29:56 INFO - 'tabpaint']}, 09:29:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:29:56 INFO - 'ts_paint', 09:29:56 INFO - 'tpaint', 09:29:56 INFO - 'sessionrestore', 09:29:56 INFO - 'sessionrestore_no_auto_restore', 09:29:56 INFO - 'tabpaint']}, 09:29:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:29:56 INFO - 'ts_paint', 09:29:56 INFO - 'tpaint', 09:29:56 INFO - 'sessionrestore', 09:29:56 INFO - 'sessionrestore_no_auto_restore', 09:29:56 INFO - 'tabpaint']}, 09:29:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:29:56 INFO - 'tests': ['a11yr', 09:29:56 INFO - 'ts_paint', 09:29:56 INFO - 'tpaint', 09:29:56 INFO - 'sessionrestore', 09:29:56 INFO - 'sessionrestore_no_auto_restore', 09:29:56 INFO - 'tabpaint']}, 09:29:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:29:56 INFO - 'tests': ['a11yr', 09:29:56 INFO - 'ts_paint', 09:29:56 INFO - 'tpaint', 09:29:56 INFO - 'sessionrestore', 09:29:56 INFO - 'sessionrestore_no_auto_restore', 09:29:56 INFO - 'tabpaint']}, 09:29:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:29:56 INFO - 'tests': ['tsvgx', 09:29:56 INFO - 'tsvgr_opacity', 09:29:56 INFO - 'tart', 09:29:56 INFO - 'tscrollx', 09:29:56 INFO - 'cart', 09:29:56 INFO - 'tsvg_static']}, 09:29:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:29:56 INFO - 'tsvgr_opacity', 09:29:56 INFO - 'tart', 09:29:56 INFO - 'tscrollx', 09:29:56 INFO - 'cart', 09:29:56 INFO - 'tsvg_static']}, 09:29:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:29:56 INFO - 'talos_options': ['--disable-e10s'], 09:29:56 INFO - 'tests': ['tp5o']}, 09:29:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:29:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:29:56 INFO - 'talos_options': ['--disable-e10s', 09:29:56 INFO - '--xperf_path', 09:29:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:56 INFO - 'tests': ['tp5n']}, 09:29:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:29:56 INFO - 'talos_options': ['--xperf_path', 09:29:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:56 INFO - 'tests': ['tp5n']}}, 09:29:56 INFO - 'talos.zip': {'path': '', 09:29:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:29:56 INFO - Running post-action listener: _resource_record_post_action 09:29:56 INFO - [mozharness: 2017-05-25 16:29:56.922952Z] Finished populate-webroot step (success) 09:29:56 INFO - [mozharness: 2017-05-25 16:29:56.923088Z] Running create-virtualenv step. 09:29:56 INFO - Running pre-action listener: _resource_record_pre_action 09:29:56 INFO - Running main action method: create_virtualenv 09:29:56 INFO - Creating virtualenv /builds/slave/test/build/venv 09:29:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:29:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:29:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:29:57 INFO - Using real prefix '/tools/python27' 09:29:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:29:57 INFO - Installing distribute.............................................................................................................................................................................................done. 09:30:00 INFO - Installing pip.................done. 09:30:00 INFO - Return code: 0 09:30:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:30:00 INFO - Reading from file tmpfile_stdout 09:30:00 INFO - Output received: 09:30:00 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:30:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:30:00 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')]} 09:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:00 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')]} 09:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1033a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91a8589fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103186d30>, '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 0x1030addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10317bbe8>, '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.lanVmrnZc2/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.6iOdqTlBN1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 09:30:00 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 09:30:00 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 09:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lanVmrnZc2/Render', 09:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:00 INFO - 'HOME': '/Users/cltbld', 09:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:00 INFO - 'LOGNAME': 'cltbld', 09:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:00 INFO - 'MOZ_NO_REMOTE': '1', 09:30:00 INFO - 'NO_EM_RESTART': '1', 09:30:00 INFO - 'PAGER': '/bin/cat', 09:30:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:00 INFO - 'PWD': '/builds/slave/test', 09:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:00 INFO - 'SHELL': '/bin/bash', 09:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iOdqTlBN1/Listeners', 09:30:00 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 09:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:00 INFO - 'USER': 'cltbld', 09:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:00 INFO - 'XPC_FLAGS': '0x0', 09:30:00 INFO - 'XPC_SERVICE_NAME': '0', 09:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:01 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 09:30:01 INFO - Cleaning up... 09:30:01 INFO - Return code: 0 09:30:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:30:01 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')]} 09:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:01 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')]} 09:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1033a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91a8589fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103186d30>, '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 0x1030addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10317bbe8>, '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.lanVmrnZc2/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.6iOdqTlBN1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 09:30:01 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 09:30:01 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 09:30:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lanVmrnZc2/Render', 09:30:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:01 INFO - 'HOME': '/Users/cltbld', 09:30:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:01 INFO - 'LOGNAME': 'cltbld', 09:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:01 INFO - 'MOZ_NO_REMOTE': '1', 09:30:01 INFO - 'NO_EM_RESTART': '1', 09:30:01 INFO - 'PAGER': '/bin/cat', 09:30:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:01 INFO - 'PWD': '/builds/slave/test', 09:30:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:01 INFO - 'SHELL': '/bin/bash', 09:30:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iOdqTlBN1/Listeners', 09:30:01 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 09:30:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:01 INFO - 'USER': 'cltbld', 09:30:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:01 INFO - 'XPC_FLAGS': '0x0', 09:30:01 INFO - 'XPC_SERVICE_NAME': '0', 09:30:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:01 INFO - Downloading/unpacking psutil>=3.1.1 09:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:30:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:03 INFO - Installing collected packages: psutil 09:30:03 INFO - Running setup.py install for psutil 09:30:03 INFO - building 'psutil._psutil_osx' extension 09:30:03 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 09:30:04 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 09:30:04 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 09:30:04 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 09:30:04 INFO - building 'psutil._psutil_posix' extension 09:30:04 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 09:30:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:30:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:30:04 INFO - ^ 09:30:04 INFO - 1 warning generated. 09:30:04 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 09:30:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:04 INFO - Successfully installed psutil 09:30:04 INFO - Cleaning up... 09:30:04 INFO - Return code: 0 09:30:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:30:04 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')]} 09:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:04 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')]} 09:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1033a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91a8589fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103186d30>, '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 0x1030addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10317bbe8>, '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.lanVmrnZc2/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.6iOdqTlBN1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 09:30:04 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 09:30:04 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 09:30:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lanVmrnZc2/Render', 09:30:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:04 INFO - 'HOME': '/Users/cltbld', 09:30:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:04 INFO - 'LOGNAME': 'cltbld', 09:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:04 INFO - 'MOZ_NO_REMOTE': '1', 09:30:04 INFO - 'NO_EM_RESTART': '1', 09:30:04 INFO - 'PAGER': '/bin/cat', 09:30:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:04 INFO - 'PWD': '/builds/slave/test', 09:30:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:04 INFO - 'SHELL': '/bin/bash', 09:30:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iOdqTlBN1/Listeners', 09:30:04 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 09:30:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:04 INFO - 'USER': 'cltbld', 09:30:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:04 INFO - 'XPC_FLAGS': '0x0', 09:30:04 INFO - 'XPC_SERVICE_NAME': '0', 09:30:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:30:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:30:06 INFO - Installing collected packages: mozsystemmonitor 09:30:06 INFO - Running setup.py install for mozsystemmonitor 09:30:06 INFO - Successfully installed mozsystemmonitor 09:30:06 INFO - Cleaning up... 09:30:06 INFO - Return code: 0 09:30:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:30:06 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')]} 09:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:06 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')]} 09:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:06 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 0x1033a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91a8589fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103186d30>, '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 0x1030addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10317bbe8>, '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.lanVmrnZc2/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.6iOdqTlBN1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 09:30:06 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 09:30:06 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 09:30:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lanVmrnZc2/Render', 09:30:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:06 INFO - 'HOME': '/Users/cltbld', 09:30:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:06 INFO - 'LOGNAME': 'cltbld', 09:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:06 INFO - 'MOZ_NO_REMOTE': '1', 09:30:06 INFO - 'NO_EM_RESTART': '1', 09:30:06 INFO - 'PAGER': '/bin/cat', 09:30:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:06 INFO - 'PWD': '/builds/slave/test', 09:30:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:06 INFO - 'SHELL': '/bin/bash', 09:30:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iOdqTlBN1/Listeners', 09:30:06 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 09:30:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:06 INFO - 'USER': 'cltbld', 09:30:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:06 INFO - 'XPC_FLAGS': '0x0', 09:30:06 INFO - 'XPC_SERVICE_NAME': '0', 09:30:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:06 INFO - Downloading/unpacking jsonschema==2.5.1 09:30:06 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 09:30:06 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 09:30:06 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 09:30:06 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 09:30:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:30:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:30:08 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 09:30:08 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 09:30:08 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 09:30:08 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 09:30:08 INFO - Downloading functools32-3.2.3-2.tar.gz 09:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:30:08 INFO - warning: no files found matching '*.txt' 09:30:08 INFO - no previously-included directories found matching 'build' 09:30:08 INFO - no previously-included directories found matching 'dist' 09:30:08 INFO - no previously-included directories found matching '.git*' 09:30:08 INFO - Installing collected packages: jsonschema, functools32 09:30:08 INFO - Running setup.py install for functools32 09:30:08 INFO - warning: no files found matching '*.txt' 09:30:08 INFO - no previously-included directories found matching 'build' 09:30:08 INFO - no previously-included directories found matching 'dist' 09:30:08 INFO - no previously-included directories found matching '.git*' 09:30:08 INFO - Successfully installed jsonschema functools32 09:30:08 INFO - Cleaning up... 09:30:08 INFO - Return code: 0 09:30:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:30: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')]} 09:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30: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')]} 09:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:08 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 0x1033a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91a8589fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103186d30>, '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 0x1030addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10317bbe8>, '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.lanVmrnZc2/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.6iOdqTlBN1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 09:30:08 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 09:30:08 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 09:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lanVmrnZc2/Render', 09:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:08 INFO - 'HOME': '/Users/cltbld', 09:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:08 INFO - 'LOGNAME': 'cltbld', 09:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:08 INFO - 'MOZ_NO_REMOTE': '1', 09:30:08 INFO - 'NO_EM_RESTART': '1', 09:30:08 INFO - 'PAGER': '/bin/cat', 09:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:08 INFO - 'PWD': '/builds/slave/test', 09:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:08 INFO - 'SHELL': '/bin/bash', 09:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iOdqTlBN1/Listeners', 09:30:08 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 09:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:08 INFO - 'USER': 'cltbld', 09:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:08 INFO - 'XPC_FLAGS': '0x0', 09:30:08 INFO - 'XPC_SERVICE_NAME': '0', 09:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:30:08 INFO - Cleaning up... 09:30:09 INFO - Return code: 0 09:30:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:30:09 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')]} 09:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:09 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')]} 09:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1033a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91a8589fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103186d30>, '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 0x1030addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10317bbe8>, '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.lanVmrnZc2/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.6iOdqTlBN1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 09:30:09 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 09:30:09 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 09:30:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lanVmrnZc2/Render', 09:30:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:09 INFO - 'HOME': '/Users/cltbld', 09:30:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:09 INFO - 'LOGNAME': 'cltbld', 09:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:09 INFO - 'MOZ_NO_REMOTE': '1', 09:30:09 INFO - 'NO_EM_RESTART': '1', 09:30:09 INFO - 'PAGER': '/bin/cat', 09:30:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:09 INFO - 'PWD': '/builds/slave/test', 09:30:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:09 INFO - 'SHELL': '/bin/bash', 09:30:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iOdqTlBN1/Listeners', 09:30:09 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 09:30:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:09 INFO - 'USER': 'cltbld', 09:30:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:09 INFO - 'XPC_FLAGS': '0x0', 09:30:09 INFO - 'XPC_SERVICE_NAME': '0', 09:30:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:09 INFO - Downloading/unpacking blobuploader==1.2.4 09:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:11 INFO - Downloading blobuploader-1.2.4.tar.gz 09:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:30:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:30:11 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 09:30:11 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 09:30:11 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 09:30:11 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 09:30:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:30:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:12 INFO - Downloading docopt-0.6.1.tar.gz 09:30:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:30:12 INFO - Installing collected packages: blobuploader, requests, docopt 09:30:12 INFO - Running setup.py install for blobuploader 09:30:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:30:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:30:12 INFO - Running setup.py install for requests 09:30:13 INFO - Running setup.py install for docopt 09:30:13 INFO - Successfully installed blobuploader requests docopt 09:30:13 INFO - Cleaning up... 09:30:13 INFO - Return code: 0 09:30:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:13 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')]} 09:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:14 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')]} 09:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1033a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91a8589fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103186d30>, '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 0x1030addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10317bbe8>, '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.lanVmrnZc2/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.6iOdqTlBN1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 09:30:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:30:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lanVmrnZc2/Render', 09:30:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:14 INFO - 'HOME': '/Users/cltbld', 09:30:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:14 INFO - 'LOGNAME': 'cltbld', 09:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:14 INFO - 'MOZ_NO_REMOTE': '1', 09:30:14 INFO - 'NO_EM_RESTART': '1', 09:30:14 INFO - 'PAGER': '/bin/cat', 09:30:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:14 INFO - 'PWD': '/builds/slave/test', 09:30:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:14 INFO - 'SHELL': '/bin/bash', 09:30:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iOdqTlBN1/Listeners', 09:30:14 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 09:30:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:14 INFO - 'USER': 'cltbld', 09:30:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:14 INFO - 'XPC_FLAGS': '0x0', 09:30:14 INFO - 'XPC_SERVICE_NAME': '0', 09:30:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:30:14 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-3vGDRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:30:14 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-g0VT0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:30:14 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-OgVvs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:30:14 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-huLjm7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:30:14 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-2kILvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:30:14 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-W9WkBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:30:14 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-DR3dRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:30:15 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-eHnbdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:30:15 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-exRwox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:30:15 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-JCaPGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:30:15 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-jAJYT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:30:15 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-XQCVV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:30:15 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-ntgD2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:30:15 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-7Ufr_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:15 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-k35tJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:30:15 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-G4Zlww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:30:16 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-GCR9gP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:30:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:30:16 INFO - Running setup.py install for manifestparser 09:30:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:30:16 INFO - Running setup.py install for mozcrash 09:30:16 INFO - Running setup.py install for mozdebug 09:30:16 INFO - Running setup.py install for mozdevice 09:30:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:30:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:30:16 INFO - Running setup.py install for mozfile 09:30:16 INFO - Running setup.py install for mozhttpd 09:30:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:30:17 INFO - Running setup.py install for mozinfo 09:30:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:30:17 INFO - Running setup.py install for mozInstall 09:30:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:30:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:30:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:30:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:30:17 INFO - Running setup.py install for mozleak 09:30:17 INFO - Running setup.py install for mozlog 09:30:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:30:17 INFO - Running setup.py install for moznetwork 09:30:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:30:17 INFO - Running setup.py install for mozprocess 09:30:17 INFO - Running setup.py install for mozprofile 09:30:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:30:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:30:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:30:18 INFO - Running setup.py install for mozrunner 09:30:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:30:18 INFO - Running setup.py install for mozscreenshot 09:30:18 INFO - Running setup.py install for moztest 09:30:18 INFO - Running setup.py install for mozversion 09:30:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:30:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:30:18 INFO - Cleaning up... 09:30:18 INFO - Return code: 0 09:30:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30: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')]} 09:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30: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')]} 09:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:18 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 0x1033a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91a8589fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103186d30>, '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 0x1030addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10317bbe8>, '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.lanVmrnZc2/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.6iOdqTlBN1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 09:30:18 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 09:30:18 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 09:30:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lanVmrnZc2/Render', 09:30:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:18 INFO - 'HOME': '/Users/cltbld', 09:30:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:18 INFO - 'LOGNAME': 'cltbld', 09:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:18 INFO - 'MOZ_NO_REMOTE': '1', 09:30:18 INFO - 'NO_EM_RESTART': '1', 09:30:18 INFO - 'PAGER': '/bin/cat', 09:30:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:18 INFO - 'PWD': '/builds/slave/test', 09:30:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:18 INFO - 'SHELL': '/bin/bash', 09:30:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iOdqTlBN1/Listeners', 09:30:18 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 09:30:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:18 INFO - 'USER': 'cltbld', 09:30:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:18 INFO - 'XPC_FLAGS': '0x0', 09:30:18 INFO - 'XPC_SERVICE_NAME': '0', 09:30:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:30:19 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-UVQ1XB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:30:19 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-mgtHCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:30:19 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-jX16RM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:30:19 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-fxgESy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:30:19 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-kW2h0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:30:19 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-khJ9Jd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:30:19 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-sWiVEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:30:19 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)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:30:19 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-k2G2Lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:30:19 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)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:30:19 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-lR6rBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:30:19 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)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:30:19 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-vQ0VvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:30:20 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-mTZ9kB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:30:20 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Tuxpao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:30:20 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-x2N3t6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:30:20 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-ntCBEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:20 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-rCoD2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:30:20 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-GXicpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:30:20 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-_DYksV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:30:20 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)) 09:30:20 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)) 09:30:20 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)) 09:30:20 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)) 09:30:20 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)) 09:30:20 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)) 09:30:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:22 INFO - Downloading blessings-1.6.tar.gz 09:30:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:30:22 INFO - Installing collected packages: blessings 09:30:22 INFO - Running setup.py install for blessings 09:30:22 INFO - Successfully installed blessings 09:30:22 INFO - Cleaning up... 09:30:22 INFO - Return code: 0 09:30:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:30:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:30:22 INFO - Reading from file tmpfile_stdout 09:30:22 INFO - Current package versions: 09:30:22 INFO - blessings == 1.6 09:30:22 INFO - blobuploader == 1.2.4 09:30:22 INFO - docopt == 0.6.1 09:30:22 INFO - functools32 == 3.2.3-2 09:30:22 INFO - jsonschema == 2.5.1 09:30:22 INFO - manifestparser == 1.1 09:30:22 INFO - mozInstall == 1.12 09:30:22 INFO - mozcrash == 1.0 09:30:22 INFO - mozdebug == 0.1 09:30:22 INFO - mozdevice == 0.49 09:30:22 INFO - mozfile == 1.2 09:30:22 INFO - mozhttpd == 0.7 09:30:22 INFO - mozinfo == 0.9 09:30:22 INFO - mozleak == 0.1 09:30:22 INFO - mozlog == 3.4 09:30:22 INFO - moznetwork == 0.27 09:30:22 INFO - mozprocess == 0.25 09:30:22 INFO - mozprofile == 0.28 09:30:22 INFO - mozrunner == 6.13 09:30:22 INFO - mozscreenshot == 0.1 09:30:22 INFO - mozsystemmonitor == 0.3 09:30:22 INFO - moztest == 0.8 09:30:22 INFO - mozversion == 1.4 09:30:22 INFO - psutil == 3.1.1 09:30:22 INFO - requests == 1.2.3 09:30:22 INFO - wsgiref == 0.1.2 09:30:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:22 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')]} 09:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:22 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')]} 09:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:22 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 0x1033a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91a8589fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103186d30>, '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 0x1030addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10317bbe8>, '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.lanVmrnZc2/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.6iOdqTlBN1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 09:30:22 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 09:30:22 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 09:30:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lanVmrnZc2/Render', 09:30:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:22 INFO - 'HOME': '/Users/cltbld', 09:30:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:22 INFO - 'LOGNAME': 'cltbld', 09:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:22 INFO - 'MOZ_NO_REMOTE': '1', 09:30:22 INFO - 'NO_EM_RESTART': '1', 09:30:22 INFO - 'PAGER': '/bin/cat', 09:30:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:22 INFO - 'PWD': '/builds/slave/test', 09:30:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:22 INFO - 'SHELL': '/bin/bash', 09:30:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iOdqTlBN1/Listeners', 09:30:22 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 09:30:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:22 INFO - 'USER': 'cltbld', 09:30:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:22 INFO - 'XPC_FLAGS': '0x0', 09:30:22 INFO - 'XPC_SERVICE_NAME': '0', 09:30:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 INFO - Cleaning up... 09:30:23 INFO - Return code: 0 09:30:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:30:23 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')]} 09:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:23 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')]} 09:30:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1033a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91a8589fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103186d30>, '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 0x1030addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10317bbe8>, '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.lanVmrnZc2/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.6iOdqTlBN1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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 09:30:23 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 09:30:23 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 09:30:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lanVmrnZc2/Render', 09:30:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:23 INFO - 'HOME': '/Users/cltbld', 09:30:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:23 INFO - 'LOGNAME': 'cltbld', 09:30:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:23 INFO - 'MOZ_NO_REMOTE': '1', 09:30:23 INFO - 'NO_EM_RESTART': '1', 09:30:23 INFO - 'PAGER': '/bin/cat', 09:30:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:23 INFO - 'PWD': '/builds/slave/test', 09:30:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:23 INFO - 'SHELL': '/bin/bash', 09:30:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iOdqTlBN1/Listeners', 09:30:23 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 09:30:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:23 INFO - 'USER': 'cltbld', 09:30:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:23 INFO - 'XPC_FLAGS': '0x0', 09:30:23 INFO - 'XPC_SERVICE_NAME': '0', 09:30:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:30:23 INFO - Cleaning up... 09:30:23 INFO - Return code: 0 09:30:23 INFO - Running post-action listener: _resource_record_post_action 09:30:23 INFO - Running post-action listener: _start_resource_monitoring 09:30:23 INFO - Starting resource monitoring. 09:30:23 INFO - [mozharness: 2017-05-25 16:30:23.449763Z] Finished create-virtualenv step (success) 09:30:23 INFO - [mozharness: 2017-05-25 16:30:23.450681Z] Running install step. 09:30:23 INFO - Running pre-action listener: _resource_record_pre_action 09:30:23 INFO - Running main action method: install 09:30:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:30:23 INFO - Reading from file tmpfile_stdout 09:30:23 INFO - Detecting whether we're running mozinstall >=1.0... 09:30:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:30:23 INFO - Reading from file tmpfile_stdout 09:30:23 INFO - Output received: 09:30:23 INFO - Usage: mozinstall [options] installer 09:30:23 INFO - Options: 09:30:23 INFO - -h, --help show this help message and exit 09:30:23 INFO - -d DEST, --destination=DEST 09:30:23 INFO - Directory to install application into. [default: 09:30:23 INFO - "/builds/slave/test"] 09:30:23 INFO - --app=APP Application being installed. [default: firefox] 09:30:23 INFO - mkdir: /builds/slave/test/build/application 09:30:23 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'] 09:30:23 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 09:30:43 INFO - Reading from file tmpfile_stdout 09:30:43 INFO - Output received: 09:30:43 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:30:43 INFO - Running post-action listener: _resource_record_post_action 09:30:43 INFO - [mozharness: 2017-05-25 16:30:43.877852Z] Finished install step (success) 09:30:43 INFO - [mozharness: 2017-05-25 16:30:43.877980Z] Running run-tests step. 09:30:43 INFO - Running pre-action listener: _resource_record_pre_action 09:30:43 INFO - Running main action method: run_tests 09:30:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:30:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:30:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:30:43 INFO - 2017-05-25 09:30:43.892 screenresolution[1625:7556] starting screenresolution argv=screenresolution get 09:30:43 INFO - 2017-05-25 09:30:43.895 screenresolution[1625:7556] Display 0: 1600x1200x32@60 09:30:43 INFO - 2017-05-25 09:30:43.901 screenresolution[1626:7558] starting screenresolution argv=screenresolution list 09:30:43 INFO - Available Modes on Display 0 09:30:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:30:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:30:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:30:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:30:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:30:44 INFO - Intel Iris: 09:30:44 INFO - Chipset Model: Intel Iris 09:30:44 INFO - Type: GPU 09:30:44 INFO - Bus: Built-In 09:30:44 INFO - VRAM (Dynamic, Max): 1536 MB 09:30:44 INFO - Vendor: Intel (0x8086) 09:30:44 INFO - Device ID: 0x0a2e 09:30:44 INFO - Revision ID: 0x0009 09:30:44 INFO - Displays: 09:30:44 INFO - Display: 09:30:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:30:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:30:44 INFO - Display Serial Number: 1600x1200 60 09:30:44 INFO - Main Display: Yes 09:30:44 INFO - Mirror: Off 09:30:44 INFO - Online: Yes 09:30:44 INFO - Rotation: Supported 09:30:44 INFO - Return code: 0 09:30:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:30:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:30:44 INFO - Python 2.7.3 09:30:44 INFO - Return code: 0 09:30:44 INFO - grabbing minidump binary from tooltool 09:30:44 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')]} 09:30:44 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 0x103186d30>, '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 0x1030addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10317bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:30:44 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 09:30:44 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 09:30:44 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 09:30:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:30:44 INFO - Return code: 0 09:30:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:30:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:30:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:30:44 INFO - ENV: RUST_BACKTRACE is now 1 09:30:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:30:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:30:44 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/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0207', '--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 09:30:44 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/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0207 --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 09:30:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lanVmrnZc2/Render', 09:30:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:44 INFO - 'HOME': '/Users/cltbld', 09:30:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:44 INFO - 'LOGNAME': 'cltbld', 09:30:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:30:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:44 INFO - 'MOZ_NO_REMOTE': '1', 09:30:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:30:44 INFO - 'NO_EM_RESTART': '1', 09:30:44 INFO - 'PAGER': '/bin/cat', 09:30:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:44 INFO - 'PWD': '/builds/slave/test', 09:30:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:30:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:44 INFO - 'RUST_BACKTRACE': '1', 09:30:44 INFO - 'SHELL': '/bin/bash', 09:30:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6iOdqTlBN1/Listeners', 09:30:44 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 09:30:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:44 INFO - 'USER': 'cltbld', 09:30:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:44 INFO - 'XPC_FLAGS': '0x0', 09:30:44 INFO - 'XPC_SERVICE_NAME': '0', 09:30:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:44 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/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0207', '--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 09:30:44 INFO - mozversion application_buildid: 20170525080444 09:30:44 INFO - mozversion application_changeset: 38c9c1ad194a58d08440919f0c4618993917315b 09:30:44 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:30:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:30:44 INFO - mozversion application_name: Firefox 09:30:44 INFO - mozversion application_remotingname: firefox-dev 09:30:44 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:30:44 INFO - mozversion application_vendor: Mozilla 09:30:44 INFO - mozversion application_version: 54.0 09:30:44 INFO - mozversion platform_buildid: 20170525080444 09:30:44 INFO - mozversion platform_changeset: 38c9c1ad194a58d08440919f0c4618993917315b 09:30:44 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:30:44 INFO - mozversion platform_version: 54.0 09:30:44 INFO - using testdate: 1495729844 09:30:44 INFO - actual date: 1495729844 09:30:44 INFO - starting webserver on 'localhost:49225' 09:30:44 INFO - SUITE-START | Running 2 tests 09:30:44 INFO - TEST-START | dromaeo_css 09:30:44 INFO - operating with platform_type : mac_ 09:30:44 INFO - Initialising browser for dromaeo_css test... 09:30:44 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmp368kii/profile http://localhost:49225/getInfo.html) 09:30:47 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 09:30:47 INFO - PROCESS | 1636 | colorDepth:24 09:30:47 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 09:30:47 INFO - PROCESS | 1636 | __metrics 09:30:47 INFO - PROCESS | 1636 | 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" 09:30:47 INFO - PROCESS | 1636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:30:47 INFO - TEST-INFO | 1636: exit 0 09:30:47 INFO - Browser initialized. 09:30:47 INFO - Running cycle 1/1 for dromaeo_css test... 09:30:47 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmp368kii/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:59 INFO - PROCESS | 1640 | RSS: Main: 193961984 09:30:59 INFO - PROCESS | 1640 | 09:34:04 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 09:34:05 INFO - PROCESS | 1640 | RSS: Main: 319696896 09:34:05 INFO - PROCESS | 1640 | 09:37:08 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 09:37:09 INFO - PROCESS | 1640 | RSS: Main: 328654848 09:37:09 INFO - PROCESS | 1640 | 09:40:11 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 09:40:12 INFO - PROCESS | 1640 | RSS: Main: 318562304 09:40:12 INFO - PROCESS | 1640 | 09:43:14 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 09:43:15 INFO - PROCESS | 1640 | RSS: Main: 301109248 09:43:15 INFO - PROCESS | 1640 | 09:46:18 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 09:46:18 INFO - PROCESS | 1640 | RSS: Main: 297631744 09:46:18 INFO - PROCESS | 1640 | 09:49:22 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 09:49:22 INFO - PROCESS | 1640 | RSS: Main: 331116544 09:49:22 INFO - PROCESS | 1640 | 09:49:22 INFO - PROCESS | 1640 | __start_tp_report 09:49:22 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 09:49:22 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 09:49:22 INFO - PROCESS | 1640 | |i|pagename|runs| 09:49:22 INFO - PROCESS | 1640 | |0;dojo.html;3406.9659303406966;3419.9227243418322;3426.366122267381;3463.3246516929207;3479.6694314040174;5837.328707198673;5865.618734782247;5890.234269544959;5893.528517718583;5944.31640361358;5904.498117660004;5971.880562388749;5976.790812321569;5977.820665380045;5985.281766188053;5333.919991200125;5367.194920761897;5437.945620543784;5446.836594902159;5466.34403871536;4755.571998520132;5274.340707411574;5280.947190528084;5299.364076310848;5316.043112239795;4494.438195225597;5384.542313903323;5399.163129714901;5440.782368705247;5470.261514695525;5428.294321738169;5439.3200849893765;5458.4541545845495;5460.754266058037;5462.6449280797015;5272.499112584298;5394.109971854639;5396.973015134939;5406.351237851483;5474.206240095204;5667.234923285345;5723.027085395493;5723.399043100456;5781;5940.70296485174;6378.585391949508;6414.807555773335;6417.101605775195;6429.58207716497;6436.806895793134;5428.809991650273;6326.797908397436;6387.329330420285;6389.297177310492;6415.6471394073305;5869.735861886225;5875.50058245054;6027.246594175728;6054.273487181523;6113.57204995655;5369.4630536946;6346.936530634727;6347.52393570484;6351.618902865843;6384.425401713868;5396.325459317585;6374.713137908805;6398.360163983658;6403.135576697086;6409.583377080475;6347.650879201596;6349.063983935492;6389.520785941073;6407.455366293917;6415.422611964852;5573.972130139405;6221.129041934133;6386.07401926718;6399.680015999182;6413.807585772434;6340.714667839959;6365.236171659338;6376.649284289316;6382.234131904108;6384.201974753156;4287.421198138274;4308.870733877989;4909.607231421478;4910.435299940481;4955.430125535538;4285.142971405731;4370.453693288339;4422.115576884636;4422.579854913778;4475.865724028285;5293.285406470154;5646.096624540054;5688.800891968761;5843.2403787507355;5944.881102377928;6374.490040796725;6409.294977552466;6415.967920160463;6418.261899881573;6419.51853610981;5892.263467066617;5898.64608123514;5903.291605007426;5937.643741375531;5978.790742324085;5373.38206106295;5383.16560933056;5450.863728406822;5537.446255374431;5554.555635549146;6448.709808058648;6481.481481481376;6508.186476690414;6568.638724869987;6572.474202063728;6377.362263773585;6386.105945167773;6391.073294372197;6391.201099862517;6410.102585637921;6256.092866534418;6474.482041436768;6568.113304703995;6570.967145164243;6571.178602674666;6235.875282494234;6267.717952692231;6275.592086514362;6283.214598175228;6313.05304204373;6180.536459765445;6220.160278362544;6370.936290637034;6387.489000880011;6404.423601875854;6195.349488303663;6202;6209.813705588839;6214.658193799384;6263.997760358321;6137.539684523589;6258.12386265914;6265.686715664289;6271.404216599416;6271.905921411091;5650.463205995424;6126.639818174547;6127.479164270909;6175.382461753788;6202.751889924352;5330.9733451332495;6322.114903913548;6349.714262858275;6401.679916004275;6417.101605775102;6238.220222472191;6251.156093927261;6275.309715931336;6289.27673317556;6304.180456540804 09:49:22 INFO - PROCESS | 1640 | |1;ext.html;784.6233807772269;787.2560430393279;813.48750287319;874.9256313213376;887.6227603268609;28316;41641;42210.15579688408;42219.15561688764;42398;43211.78394108025;43222.70331890041;43291.917702057464;43336.3499547507;45012;7614.505057171294;9530.046995300467;9706.272029597809;9715.417074975525;9718.805623887554;7673.040869891263;11648.242864213842;11932.522699092026;12091.818622720666;12096.637100886988;11895.583654572092;11924.880751192508;11927.045836333073;11929.164958452911;11948.70128246792;11796.11529135314;11854.170208085394;11865.466054027589;11867.643970680894;11874.346910919896;11496.31022138666;11509.539618415254;11551.942240288745;11558.95969362753;11563.53745850165;41034.79482602598;41315.38026929599;41339.793301033606;41383.58616413827;41406;3820.9044773880596;3857.787821669807;3910.393888947189;3916.471276377681;3975.383815508601;28114.578281325797;29196.124116276547;29908.401831963456;29983.550246746316;30553;3064.923376915595;3122.2974830663147;3185.729213016896;3254.4142054430195;3279.502119169798;370747;371326;371682;371826;372172;54168.729156354755;54289.45710542844;54306.45693543093;54365.72817135968;54507;56019;56026;56041;56065.719671401384;56091.43908560944;15060.774088388684;15183.468578599695;15189;15189.16459594733;15192.848071519364;11299.209055366046;11469.88530114705;13053.28206948627;13087.541936032194;13104.868951310556;9283.76790580241;10768.515416806262;10797.190210734227;10798.784024319626;12417.813732794015;4442.034388485934;5835;5854.941450585439;5861.9706901465215;5880.647161170344;939.2345238630494;950.8811398575178;981.3474039763478;987.4026214140486;1024.3802499487704;29700;29836.980906326426;30002.84998574993;30033;30185.69814301873;1057.471264367816;1057.9949048404046;1085.592902661502;1088.2436706488913;1099.7525556749667;15426.922865385826;15472;15541.378344866078;15565.688686226438;15731.68536629261;462783;463600;464923;464980;465388;59339.406605933385;59374.40625593775;59518;59582;59864.40135598588;1200.717831309624;1218.7629556000666;1471.4261438039387;1509.3434356055152;1513.341696362086;27872.8850845972;29935.275080906253;30581.847090764404;30590.62342194651;30968.380632386776;46454.53545464637;47354.28968565543;48846;49115.75442122766;49250.26124608062;479854;483759;484042.5372350786;484728;485409;24139.758602414455;24531.63202551999;29412.264693382836;30270.57835373209;30409;38867.80566097151;39592.80203598963;40443.595564043986;40469;40513.79743101266;26661;28025.01912433055;28172.43655126927;28362.00732974336;29256.82972681069;27430.451390972466;27901.209879011938;28183.013594524095;28276.717232828232;29107.126786196448;32611;33147.50278745869;33599.328013440085;33636.49545256872;33643.49534756932;27997.720022799513;28289;28610.85694571514;29003.41993160167;29651.555226672048;22176.334709958726;23303.41741456477;23379.76863408762;23538.94074766674;23615 09:49:22 INFO - PROCESS | 1640 | |2;jquery.html;420.4975054809504;429.084328205727;430.5823351349192;437.73517022201565;441.59152783675114;19605;20848.687269690967;21490.355289341307;21714.3485695429;21821.23625673102;21435.678464823002;22412.32763017108;22873.885630571865;22926.197583084595;23194.536109277848;6636.137302150713;6704.765333213339;6920.515563910529;6957.79126626202;6976.337247961461;6762.763303284386;6899.896501552481;6906.033155358254;6921.5847049176955;6938.514303998722;6553.180852393451;6791.728330866759;6896.689648965808;6956.79129626112;6969.965150174242;6050.54620903434;6733.158355205599;6788.660566971632;6790.151231096113;6809.216940051908;6025.668588227646;6621.437177839889;6678.265390807056;6686.93313066868;6687.966560167217;26603.733962660513;26897.193084207505;26933.192004239903;27032.729672703415;27287.727122728917;2967.406518696248;2978.731914127717;2979.7318241358166;2991.6110905582136;3006.8947586834356;15862.682746345125;16604.25280862364;16731.83268167315;16757.41349052777;17428.12859357027;2100.5588826346484;2165.621016322137;2166.295953815016;2167.729033870766;2168.2194410012385;122315;127183.36408317991;127745.72254277431;127952;130645.34677326647;19766.70349944752;20799.480012999495;20904.581908361746;20950.790492095188;20982.2656207032;32723;33768.66231337655;34179.65820341815;34236;34304.82847585746;10593.89406105929;10595.311304765166;10840.891591084146;10940.781184376268;10945.616903408343;8813.735587932373;8824.823503529893;8853.070427605138;9011.188992990663;9036.909630903738;8809.295256379473;9296.674616388394;10049.447280399501;10136;10140.340877842917;3507.9649203508147;4088.1619268049976;4095.8361665533043;4118.402831589403;4149.7095203335475;903.746950853762;926.1710768861831;928.1831987850783;936.4615346175976;963.5326866469757;17142.657146857244;21150.048247828883;22326;22419.663705044437;22589;969.2924165359186;969.3989726369696;986.8914419413859;990.8860481044773;996.7557948302567;9535.141837234682;11397.373144477133;11686;11773.52905883755;11806.291622502677;724376.130739664;734455;754214;768007;782502;116875;120292.39853800675;123848;124150;124409.9999999982;1210.9636710898687;1259.7165637731437;1303.9022073344727;1307.378994977397;1310.1156719214682;18739.625207496047;18881.71677424867;19346.613067738286;19477.902610486857;19480.902595486932;48615.27077093776;50006;64706;64842.67578662076;64976.70046598947;89665.55167224123;92998.53500732454;95754;96179.51910240404;97529.51235243779;7084.149902011792;10119.241056920908;10797.676069717922;11145.554177832795;11155.330680159217;11967.64097077089;12091.57679481214;12266.632001039983;12508.374581270718;12550.435230414836;10637.468126593794;10676.62631807883;10720.892791071989;10870.456477176267;10955.452227388758;9946.751331216778;10023.498825058572;10029.097381235724;10290.176785856964;10317.226208034277;10812.891871081189;10919.39943303126;10991.560337586407;11150.330980141218;11174.497147628213;10853;10916.727081823017;10957.56169753201;11093.5562577496;11123.83314250303;6000.939990600038;10567.313124646873;10713.732156696145;10719;10765.246432749633 09:49:22 INFO - PROCESS | 1640 | |3;mootools.html;1358.836939567252;1592.984070159298;1614.6609212065462;1619.927103280353;1641.2860405723509;9249.260059195265;9666.661666841663;10983.121350291987;11108;11533.884661153386;11187.32876027439;11270.887291127086;11290.830637540443;11297.322160670366;11313.208075434723;2276.4422716434497;2307.492351682627;2310.0759696121518;2330.6737056811976;2331.7901388875;1959.6472634925708;2040.5918816236738;2041.2345370486069;2052.538178909741;2053.7638171610306;1998.1607724755565;2244.45010972312;2303.8156947444163;2310.1452462588863;2321.5472982768365;2310.792028717415;2324.4421338878547;2325.6395258734924;2327.3134425344397;2328.6157783965627;2258.9887050564807;2278.7037685100995;2293.6788849560908;2297.425643589103;2297.8046866016243;9212.309076819265;11200.439978001148;11260.54957801687;11271.26736762116;11281.92821681939;5383.596230282745;5551.8889622207325;5552.139418390116;5564.554835613141;5590.468905453975;3136.5582316451623;3214.976091158821;3269.591301087364;3321.8173000484962;3350.363430948112;1101.713554475842;1110.4614262082882;1128.7911736328806;1145.5532342386393;1148.5979907032556;15462;15653;15731.213439327988;15755.606109847116;15787.28957196929;4269.48766148058;4466.441694788152;4514.977425112854;4526.20791361517;4573.131105090022;12661.873381266254;12669.429875655618;12676.176048556814;12686;12688.429020694091;5453.291072160673;5468.781248750085;5471.987682278791;5499.642523235977;5502.532284755841;4874.902501949941;4971.304017437562;4974.552290293891;5037.319961805183;5139.203423469368;2845.231787417386;3223.7098661120613;3477.94783078254;3714.8483969969398;3763.5483741951143;946.990530094704;956.2111258212003;1003.5935446144323;1025.8512515685334;1027.753339198602;800.6917336825364;824.056455358623;835.6156168162713;847.5042100371352;878.7012415778665;6647.60114393138;6782;6811.352921472452;6913.10129683148;7018.613976231356;942.3309450290234;1012.6000229909145;1051.6161601015726;1058.9311694739818;1077.0844781935448;4375.409319741858;4427.203103441411;4509.909801803946;4588.816447342136;4591.288350305708;13532.729345413036;13837.169769813843;13979.580612581638;14016;14032.36854341557;13075.476980920657;13423.932880335537;13861.86138138626;13886;13893.513727019708;9860.605575776888;10148.289619726696;10153.593856245816;10164.237682173718;10178.033086856736;6716.328367163244;6961.721531138698;7138.750143744944;7142.857142857218;7227.530210536298;11057.944710276397;11962.641120766391;12584.119111662461;12630;12676.87323126757;11141.498632561392;12400.945919596936;12488.12583119173;12621.116521843383;12694.619161425171;9317.021712720067;9351.53242337894;9525.095115963972;9560.426374417557;9575.760605984628;10346.878889827718;10358.5338659762;10450.634227801991;10457.633982810565;10474.214433917334;8427.95786021066;8654.30765538768;8673.869891951499;8711.607977640895;8877.911220887709;8765.298776097768;8792;8801.691940782042;8871.979722331756;8896.777580560538;7921.247481489249;8071.919280807117;8084.070331911906;8088.312493438124;8093.514389136671;4613.838515652071;5954.553408494294;5974.940250597612;6076.361981992004;6152.753889844356;5021.72380519075;5612.681019960224;6156.292026416841;6178.135061091541;6218.657973811484 09:49:22 INFO - PROCESS | 1640 | |4;prototype.html;329.8664041063369;381.98011309802826;386.1870762045895;391.1785250972956;391.27223364541965;4863.051704917542;4939.456659767432;5182.559482443992;5234.7644356003975;5268.31511903452;4561.247394179956;4887.657863949525;4929.450795427296;5082.847514574569;5167.9483205168035;3886.961130388688;4011.2980228460046;4018.7789671568053;4055.81748821303;4088.853351990214;3576.3204991051616;3818.639597154514;4044.9797751011206;4047.919041619166;4063.2279866825356;4054.9391759123637;4069.9796501017454;4082.4896887889013;4102.466679331683;4255.319148936171;3511.7190624750215;3525.2068284636007;3756.8309426075893;3936.1266275618814;3942;3460.688538031564;3854.7879866607327;3874.1476875087437;3886.261610294035;3906.8046597670286;3849.826757795906;4493.842955381435;4907.601333619901;4968.962725282653;4969.304297398331;4613.354130421743;4733.83431579893;4891.6736619099;4923.58149557288;4949.698390248515;4327.870163895057;4506.526814684476;4965.379327584052;5010.2735103409805;5332.866678333073;3976.1053762903402;4209.831606735727;4343.2399330117105;4345.652347812167;4418.961544037149;4874.026019462052;5357;5645.515972971879;5785.71071446426;5849.7660093596205;4070.776107314067;4155.688323375759;4301.78491075445;4377.846775362847;4439.667024973075;4480.484744254452;4486.468666646645;4490.991036005884;4507.278835386388;4509.751963642031;4813.205821039559;4850.08421673437;4879.121758083508;4900.509949005071;4912.680675756064;4713.434387873409;5146.511081447256;5332.093544097513;5381.408045115034;5383.703896285742;1394.4939465377367;1408.6476982097097;1423.9391653218418;1427.9214643194516;1517.6205948512873;1088.9509972051276;1158.8609366876028;1161.872194058653;1168.661088284405;1174.3188950408742;4361.629261512743;4440.844570440084;4467.46034916701;4610.953890461053;4672.504798464527;4987.975060124676;5373.2387328869945;5615.466530679579;5657.19968615538;5667.603267771216;4590.311453282035;4661.766911654404;4851.636127290467;4914.33656456381;4941.085899108676;2550.0182429764304;2814.8311101333984;2858.6426696662916;2859.7140285971236;2879.7408233259107;5644.911382788395;5776.971115144397;5784.768609255582;5795;5821.126830975303;5209.921851172236;5482.698451585201;5493.890122197614;5794.739236734357;5832.504237139806;4978.47725988766;5612.410696876851;5623.297087864017;5753.712314384264;5847;4740.715557066587;5317.867053323698;5392.7573259202645;5612.249997501277;5625.493705566519;4726.432828060655;4914.483979182278;5062.493750624908;5245.344331958505;5265.578753699771;5791.305043394819;5796.913046304393;5807;5854.5609079320075;5856.443637854397;3858.8649397272084;4440.777961101868;4504.527024662363;4562.338460923082;4579.519150489151;4989.775460104232;5138.948610513997;5416.1688570291035;5537.2524709163745;5586.329640443173;4640.721556706609;5412.621116521806;5440.238366628754;5452;5455.972720136374;4488.506264310989;5407.270018547446;5457.672539647634;5460.399356070909;5501.259143782267;4051.858184963512;4505.341848309382;5301.773255232734;5311.468853114658;5355.651882627617;5406.567474601944;5436.592255580927;5440.7551660174595;5468.179773034077;5473.698946557818;4348.2825333819565;4394.824207031683;4803.5916947058495;4931.605471562339;5383.084875571084 09:49:22 INFO - PROCESS | 1640 | |5;yui.html;752.7854561449981;766.2031487253257;781.5623250979452;826.7437094500705;840.8192238668687;15206.391744330227;16574.50276491704;16767.24547395367;16819.831801681994;16828.411005614806;12133.817992730115;14220.573382798506;14360.281985900716;14374.640633984156;14398.712025759478;972.3873959405582;994.9602015919354;996.6960077176458;997.147438939707;997.2321312589302;810.9553974531399;986.4130842148945;993.9950300248489;1005.8541511480831;1008.1380419741125;1041.0682439216935;1042.9269951103427;1056.6724315462193;1060.7401186709267;1061.2995423020807;1033.7415646088477;1033.7725700345911;1035.6582327831836;1037.1599004806094;1038.1331588123926;771.4522688890838;771.6681826814468;772.9304362607336;773.8336257704565;774.1600363605495;16055.438059667973;16217.270222840001;16312.673746525003;16904.070276134808;17108.973461592344;13767.31163441824;14390.136591804525;14868.182249976247;15436.845631543765;15599.532014039483;11695.047323152305;11734.23727457721;11932.522699092026;12005;12424.378781061001;3764.7552909061096;3824.961750382516;3849.4225866120028;4002.3796311571464;4004.6395824375945;91226.5438672809;95553;104952;106015.46992265066;106248.46875765572;11592.536298547964;11601.245919015406;11823.231489953125;11875.109366797526;12443.377831108408;25071.247862564152;25154.24537263885;25168.74831251664;25191.748082519305;25249.11628093008;8179.959100204461;8329.833403331899;8433.40966132377;8452.8732069019;8624.827503450022;4226.450561426995;4413.007073408522;4492.528284530143;4499.100179963955;4674.742889141062;2902.3469719313407;3452.8100954447746;3457.9654203457644;3552.182997910495;3663.725220608465;684.7295318349323;687.7730348984823;690.6650274616807;702.550367764631;704.5807744392079;664.2095905871997;679.212113947828;691.8132104331802;702.5328156775789;724.1744411371042;10080.747981300527;10258.17934565233;10401.84397234042;10533.473326333653;10533.631322903662;677.6577828196828;705.7459314646723;706.9823254418578;711.959027650386;741.4328039050131;4974.228994505857;5162.174052151638;5170.560502357266;5186.429492755794;5194.584433245407;87714;103959;106415.86730615476;107114.9288507105;109453;80720.19279807126;86746.1325386738;87053;89325.55337223402;91283;82154.17845821584;82992.17007829844;83227.5838620803;85223.5738821302;86309;9607.279454041114;9849.950750246202;9907.405555666683;9915.504224788876;9931.801363972825;33873.97260273973;51654;51989.7400512995;52129;54108.45891541192;62222.377776221656;71297;71439;75068;75937;13045.152065115737;13064.80402793978;13167.93416032952;13272.27002514871;13551;12039.217450865666;14320.856791431952;14535.927320363331;14831.92584037073;15906.204689765234;12771.680707982376;13560.45758169662;13659.872072475988;13793.793093103412;14129.010969232055;13785.310734463437;13846.93076534611;14304.928475357556;14499.78250326267;14653.142637185647;13432.462701491831;14277.214753188675;14395.496157634432;14406.495772648326;14637.26813659334;9053;9563.187129093827;9737.269704772028;9816.607335706492;9986.300958932805;8771.982384133778;9403.34176607631;10015.749606259902;10716.785664286515;11164 09:49:22 INFO - PROCESS | 1640 | __end_tp_report 09:49:22 INFO - PROCESS | 1640 | __start_cc_report 09:49:22 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,1327 09:49:22 INFO - PROCESS | 1640 | __end_cc_report 09:49:22 INFO - PROCESS | 1640 | __startTimestamp1495730962371__endTimestamp 09:49:22 INFO - PROCESS | 1640 | 09:49:22 INFO - PROCESS | 1640 | ------- Summary: start ------- 09:49:22 INFO - PROCESS | 1640 | Number of tests: 6 09:49:22 INFO - PROCESS | 1640 | 09:49:22 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5851.64 Median:6127.48 stddev:695.86 (11.4%) stddev-sans-first:671.05 09:49:22 INFO - PROCESS | 1640 | Values: 3407.0 3419.9 3426.4 3463.3 3479.7 5837.3 5865.6 5890.2 5893.5 5944.3 5904.5 5971.9 5976.8 5977.8 5985.3 5333.9 5367.2 5437.9 5446.8 5466.3 4755.6 5274.3 5280.9 5299.4 5316.0 4494.4 5384.5 5399.2 5440.8 5470.3 5428.3 5439.3 5458.5 5460.8 5462.6 5272.5 5394.1 5397.0 5406.4 5474.2 5667.2 5723.0 5723.4 5781.0 5940.7 6378.6 6414.8 6417.1 6429.6 6436.8 5428.8 6326.8 6387.3 6389.3 6415.6 5869.7 5875.5 6027.2 6054.3 6113.6 5369.5 6346.9 6347.5 6351.6 6384.4 5396.3 6374.7 6398.4 6403.1 6409.6 6347.7 6349.1 6389.5 6407.5 6415.4 5574.0 6221.1 6386.1 6399.7 6413.8 6340.7 6365.2 6376.6 6382.2 6384.2 4287.4 4308.9 4909.6 4910.4 4955.4 4285.1 4370.5 4422.1 4422.6 4475.9 5293.3 5646.1 5688.8 5843.2 5944.9 6374.5 6409.3 6416.0 6418.3 6419.5 5892.3 5898.6 5903.3 5937.6 5978.8 5373.4 5383.2 5450.9 5537.4 5554.6 6448.7 6481.5 6508.2 6568.6 6572.5 6377.4 6386.1 6391.1 6391.2 6410.1 6256.1 6474.5 6568.1 6571.0 6571.2 6235.9 6267.7 6275.6 6283.2 6313.1 6180.5 6220.2 6370.9 6387.5 6404.4 6195.3 6202.0 6209.8 6214.7 6264.0 6137.5 6258.1 6265.7 6271.4 6271.9 5650.5 6126.6 6127.5 6175.4 6202.8 5331.0 6322.1 6349.7 6401.7 6417.1 6238.2 6251.2 6275.3 6289.3 6304.2 09:49:22 INFO - PROCESS | 1640 | 09:49:22 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:57974.18 Median:17045.34 stddev:117490.62 (689.3%) stddev-sans-first:117741.75 09:49:22 INFO - PROCESS | 1640 | Values: 784.6 787.3 813.5 874.9 887.6 28316.0 41641.0 42210.2 42219.2 42398.0 43211.8 43222.7 43291.9 43336.3 45012.0 7614.5 9530.0 9706.3 9715.4 9718.8 7673.0 11648.2 11932.5 12091.8 12096.6 11895.6 11924.9 11927.0 11929.2 11948.7 11796.1 11854.2 11865.5 11867.6 11874.3 11496.3 11509.5 11551.9 11559.0 11563.5 41034.8 41315.4 41339.8 41383.6 41406.0 3820.9 3857.8 3910.4 3916.5 3975.4 28114.6 29196.1 29908.4 29983.6 30553.0 3064.9 3122.3 3185.7 3254.4 3279.5 370747.0 371326.0 371682.0 371826.0 372172.0 54168.7 54289.5 54306.5 54365.7 54507.0 56019.0 56026.0 56041.0 56065.7 56091.4 15060.8 15183.5 15189.0 15189.2 15192.8 11299.2 11469.9 13053.3 13087.5 13104.9 9283.8 10768.5 10797.2 10798.8 12417.8 4442.0 5835.0 5854.9 5862.0 5880.6 939.2 950.9 981.3 987.4 1024.4 29700.0 29837.0 30002.8 30033.0 30185.7 1057.5 1058.0 1085.6 1088.2 1099.8 15426.9 15472.0 15541.4 15565.7 15731.7 462783.0 463600.0 464923.0 464980.0 465388.0 59339.4 59374.4 59518.0 59582.0 59864.4 1200.7 1218.8 1471.4 1509.3 1513.3 27872.9 29935.3 30581.8 30590.6 30968.4 46454.5 47354.3 48846.0 49115.8 49250.3 479854.0 483759.0 484042.5 484728.0 485409.0 24139.8 24531.6 29412.3 30270.6 30409.0 38867.8 39592.8 40443.6 40469.0 40513.8 26661.0 28025.0 28172.4 28362.0 29256.8 27430.5 27901.2 28183.0 28276.7 29107.1 32611.0 33147.5 33599.3 33636.5 33643.5 27997.7 28289.0 28610.9 29003.4 29651.6 22176.3 23303.4 23379.8 23538.9 23615.0 09:49:22 INFO - PROCESS | 1640 | 09:49:22 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:41640.65 Median:22731.44 stddev:124388.78 (547.2%) stddev-sans-first:124699.21 09:49:22 INFO - PROCESS | 1640 | Values: 420.5 429.1 430.6 437.7 441.6 19605.0 20848.7 21490.4 21714.3 21821.2 21435.7 22412.3 22873.9 22926.2 23194.5 6636.1 6704.8 6920.5 6957.8 6976.3 6762.8 6899.9 6906.0 6921.6 6938.5 6553.2 6791.7 6896.7 6956.8 6970.0 6050.5 6733.2 6788.7 6790.2 6809.2 6025.7 6621.4 6678.3 6686.9 6688.0 26603.7 26897.2 26933.2 27032.7 27287.7 2967.4 2978.7 2979.7 2991.6 3006.9 15862.7 16604.3 16731.8 16757.4 17428.1 2100.6 2165.6 2166.3 2167.7 2168.2 122315.0 127183.4 127745.7 127952.0 130645.3 19766.7 20799.5 20904.6 20950.8 20982.3 32723.0 33768.7 34179.7 34236.0 34304.8 10593.9 10595.3 10840.9 10940.8 10945.6 8813.7 8824.8 8853.1 9011.2 9036.9 8809.3 9296.7 10049.4 10136.0 10140.3 3508.0 4088.2 4095.8 4118.4 4149.7 903.7 926.2 928.2 936.5 963.5 17142.7 21150.0 22326.0 22419.7 22589.0 969.3 969.4 986.9 990.9 996.8 9535.1 11397.4 11686.0 11773.5 11806.3 724376.1 734455.0 754214.0 768007.0 782502.0 116875.0 120292.4 123848.0 124150.0 124410.0 1211.0 1259.7 1303.9 1307.4 1310.1 18739.6 18881.7 19346.6 19477.9 19480.9 48615.3 50006.0 64706.0 64842.7 64976.7 89665.6 92998.5 95754.0 96179.5 97529.5 7084.1 10119.2 10797.7 11145.6 11155.3 11967.6 12091.6 12266.6 12508.4 12550.4 10637.5 10676.6 10720.9 10870.5 10955.5 9946.8 10023.5 10029.1 10290.2 10317.2 10812.9 10919.4 10991.6 11150.3 11174.5 10853.0 10916.7 10957.6 11093.6 11123.8 6000.9 10567.3 10713.7 10719.0 10765.2 09:49:22 INFO - PROCESS | 1640 | 09:49:22 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:6730.81 Median:2329.64 stddev:4333.00 (186.0%) stddev-sans-first:4326.36 09:49:22 INFO - PROCESS | 1640 | Values: 1358.8 1593.0 1614.7 1619.9 1641.3 9249.3 9666.7 10983.1 11108.0 11533.9 11187.3 11270.9 11290.8 11297.3 11313.2 2276.4 2307.5 2310.1 2330.7 2331.8 1959.6 2040.6 2041.2 2052.5 2053.8 1998.2 2244.5 2303.8 2310.1 2321.5 2310.8 2324.4 2325.6 2327.3 2328.6 2259.0 2278.7 2293.7 2297.4 2297.8 9212.3 11200.4 11260.5 11271.3 11281.9 5383.6 5551.9 5552.1 5564.6 5590.5 3136.6 3215.0 3269.6 3321.8 3350.4 1101.7 1110.5 1128.8 1145.6 1148.6 15462.0 15653.0 15731.2 15755.6 15787.3 4269.5 4466.4 4515.0 4526.2 4573.1 12661.9 12669.4 12676.2 12686.0 12688.4 5453.3 5468.8 5472.0 5499.6 5502.5 4874.9 4971.3 4974.6 5037.3 5139.2 2845.2 3223.7 3477.9 3714.8 3763.5 947.0 956.2 1003.6 1025.9 1027.8 800.7 824.1 835.6 847.5 878.7 6647.6 6782.0 6811.4 6913.1 7018.6 942.3 1012.6 1051.6 1058.9 1077.1 4375.4 4427.2 4509.9 4588.8 4591.3 13532.7 13837.2 13979.6 14016.0 14032.4 13075.5 13423.9 13861.9 13886.0 13893.5 9860.6 10148.3 10153.6 10164.2 10178.0 6716.3 6961.7 7138.8 7142.9 7227.5 11057.9 11962.6 12584.1 12630.0 12676.9 11141.5 12400.9 12488.1 12621.1 12694.6 9317.0 9351.5 9525.1 9560.4 9575.8 10346.9 10358.5 10450.6 10457.6 10474.2 8428.0 8654.3 8673.9 8711.6 8877.9 8765.3 8792.0 8801.7 8872.0 8896.8 7921.2 8071.9 8084.1 8088.3 8093.5 4613.8 5954.6 5974.9 6076.4 6152.8 5021.7 5612.7 6156.3 6178.1 6218.7 09:49:22 INFO - PROCESS | 1640 | 09:49:22 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4485.12 Median:4850.86 stddev:1266.90 (26.1%) stddev-sans-first:1231.47 09:49:22 INFO - PROCESS | 1640 | Values: 329.9 382.0 386.2 391.2 391.3 4863.1 4939.5 5182.6 5234.8 5268.3 4561.2 4887.7 4929.5 5082.8 5167.9 3887.0 4011.3 4018.8 4055.8 4088.9 3576.3 3818.6 4045.0 4047.9 4063.2 4054.9 4070.0 4082.5 4102.5 4255.3 3511.7 3525.2 3756.8 3936.1 3942.0 3460.7 3854.8 3874.1 3886.3 3906.8 3849.8 4493.8 4907.6 4969.0 4969.3 4613.4 4733.8 4891.7 4923.6 4949.7 4327.9 4506.5 4965.4 5010.3 5332.9 3976.1 4209.8 4343.2 4345.7 4419.0 4874.0 5357.0 5645.5 5785.7 5849.8 4070.8 4155.7 4301.8 4377.8 4439.7 4480.5 4486.5 4491.0 4507.3 4509.8 4813.2 4850.1 4879.1 4900.5 4912.7 4713.4 5146.5 5332.1 5381.4 5383.7 1394.5 1408.6 1423.9 1427.9 1517.6 1089.0 1158.9 1161.9 1168.7 1174.3 4361.6 4440.8 4467.5 4611.0 4672.5 4988.0 5373.2 5615.5 5657.2 5667.6 4590.3 4661.8 4851.6 4914.3 4941.1 2550.0 2814.8 2858.6 2859.7 2879.7 5644.9 5777.0 5784.8 5795.0 5821.1 5209.9 5482.7 5493.9 5794.7 5832.5 4978.5 5612.4 5623.3 5753.7 5847.0 4740.7 5317.9 5392.8 5612.2 5625.5 4726.4 4914.5 5062.5 5245.3 5265.6 5791.3 5796.9 5807.0 5854.6 5856.4 3858.9 4440.8 4504.5 4562.3 4579.5 4989.8 5138.9 5416.2 5537.3 5586.3 4640.7 5412.6 5440.2 5452.0 5456.0 4488.5 5407.3 5457.7 5460.4 5501.3 4051.9 4505.3 5301.8 5311.5 5355.7 5406.6 5436.6 5440.8 5468.2 5473.7 4348.3 4394.8 4803.6 4931.6 5383.1 09:49:22 INFO - PROCESS | 1640 | 09:49:22 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20771.40 Median:3505.07 stddev:29371.30 (838.0%) stddev-sans-first:29415.23 09:49:22 INFO - PROCESS | 1640 | Values: 752.8 766.2 781.6 826.7 840.8 15206.4 16574.5 16767.2 16819.8 16828.4 12133.8 14220.6 14360.3 14374.6 14398.7 972.4 995.0 996.7 997.1 997.2 811.0 986.4 994.0 1005.9 1008.1 1041.1 1042.9 1056.7 1060.7 1061.3 1033.7 1033.8 1035.7 1037.2 1038.1 771.5 771.7 772.9 773.8 774.2 16055.4 16217.3 16312.7 16904.1 17109.0 13767.3 14390.1 14868.2 15436.8 15599.5 11695.0 11734.2 11932.5 12005.0 12424.4 3764.8 3825.0 3849.4 4002.4 4004.6 91226.5 95553.0 104952.0 106015.5 106248.5 11592.5 11601.2 11823.2 11875.1 12443.4 25071.2 25154.2 25168.7 25191.7 25249.1 8180.0 8329.8 8433.4 8452.9 8624.8 4226.5 4413.0 4492.5 4499.1 4674.7 2902.3 3452.8 3458.0 3552.2 3663.7 684.7 687.8 690.7 702.6 704.6 664.2 679.2 691.8 702.5 724.2 10080.7 10258.2 10401.8 10533.5 10533.6 677.7 705.7 707.0 712.0 741.4 4974.2 5162.2 5170.6 5186.4 5194.6 87714.0 103959.0 106415.9 107114.9 109453.0 80720.2 86746.1 87053.0 89325.6 91283.0 82154.2 82992.2 83227.6 85223.6 86309.0 9607.3 9850.0 9907.4 9915.5 9931.8 33874.0 51654.0 51989.7 52129.0 54108.5 62222.4 71297.0 71439.0 75068.0 75937.0 13045.2 13064.8 13167.9 13272.3 13551.0 12039.2 14320.9 14535.9 14831.9 15906.2 12771.7 13560.5 13659.9 13793.8 14129.0 13785.3 13846.9 14304.9 14499.8 14653.1 13432.5 14277.2 14395.5 14406.5 14637.3 9053.0 9563.2 9737.3 9816.6 9986.3 8772.0 9403.3 10015.7 10716.8 11164.0 09:49:22 INFO - PROCESS | 1640 | -------- Summary: end -------- 09:49:22 INFO - PROCESS | 1640 | 09:49:22 INFO - TEST-INFO | 1640: exit 0 09:49:22 INFO - TEST-OK | dromaeo_css | took 1118364ms 09:49:22 INFO - TEST-START | kraken 09:49:22 INFO - operating with platform_type : mac_ 09:49:22 INFO - Initialising browser for kraken test... 09:49:22 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpSarOnD/profile http://localhost:49225/getInfo.html) 09:49:25 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 09:49:25 INFO - PROCESS | 1649 | colorDepth:24 09:49:25 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 09:49:25 INFO - PROCESS | 1649 | __metrics 09:49:25 INFO - PROCESS | 1649 | 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" 09:49:25 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:49:25 INFO - TEST-INFO | 1649: exit 0 09:49:25 INFO - Browser initialized. 09:49:25 INFO - Running cycle 1/1 for kraken test... 09:49:25 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpSarOnD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:37 INFO - PROCESS | 1651 | RSS: Main: 194052096 09:49:37 INFO - PROCESS | 1651 | 09:49:40 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 09:49:41 INFO - PROCESS | 1651 | RSS: Main: 244871168 09:49:41 INFO - PROCESS | 1651 | 09:49:44 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 09:49:44 INFO - PROCESS | 1651 | RSS: Main: 259264512 09:49:44 INFO - PROCESS | 1651 | 09:49:48 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 09:49:48 INFO - PROCESS | 1651 | RSS: Main: 356450304 09:49:48 INFO - PROCESS | 1651 | 09:49:50 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 09:49:51 INFO - PROCESS | 1651 | RSS: Main: 259162112 09:49:51 INFO - PROCESS | 1651 | 09:49:53 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:49:53 INFO - PROCESS | 1651 | RSS: Main: 260784128 09:49:53 INFO - PROCESS | 1651 | 09:49:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 09:49:57 INFO - PROCESS | 1651 | RSS: Main: 299847680 09:49:57 INFO - PROCESS | 1651 | 09:50:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 09:50:01 INFO - PROCESS | 1651 | RSS: Main: 301875200 09:50:01 INFO - PROCESS | 1651 | 09:50:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 09:50:04 INFO - PROCESS | 1651 | RSS: Main: 298717184 09:50:04 INFO - PROCESS | 1651 | 09:50:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:50:06 INFO - PROCESS | 1651 | RSS: Main: 277610496 09:50:06 INFO - PROCESS | 1651 | 09:50:08 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:50:09 INFO - PROCESS | 1651 | RSS: Main: 280645632 09:50:09 INFO - PROCESS | 1651 | 09:50:11 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:50:12 INFO - PROCESS | 1651 | RSS: Main: 289202176 09:50:12 INFO - PROCESS | 1651 | 09:50:15 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:50:15 INFO - PROCESS | 1651 | RSS: Main: 300187648 09:50:15 INFO - PROCESS | 1651 | 09:50:18 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:50:19 INFO - PROCESS | 1651 | RSS: Main: 294076416 09:50:19 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 09:50:21 INFO - PROCESS | 1651 | RSS: Main: 339148800 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | __start_tp_report 09:50:21 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 09:50:21 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 09:50:21 INFO - PROCESS | 1651 | |i|pagename|runs| 09:50:21 INFO - PROCESS | 1651 | |0;ai-astar;104;115;116;115;115;117;115;115;115;115 09:50:21 INFO - PROCESS | 1651 | |1;audio-beat-detection;150;140;139;141;110;110;110;107;146;137 09:50:21 INFO - PROCESS | 1651 | |2;audio-dft;185;184;183;187;186;185;182;184;185;186 09:50:21 INFO - PROCESS | 1651 | |3;audio-fft;83;60;59;89;60;61;61;60;75;57 09:50:21 INFO - PROCESS | 1651 | |4;audio-oscillator;86;73;86;69;69;68;82;68;69;82 09:50:21 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;87;87;87;86;87;87;87;87;91;136 09:50:21 INFO - PROCESS | 1651 | |6;imaging-darkroom;94;94;94;95;93;93;95;95;92;94 09:50:21 INFO - PROCESS | 1651 | |7;imaging-desaturate;86;84;86;87;86;84;85;84;86;87 09:50:21 INFO - PROCESS | 1651 | |8;json-parse-financial;63;60;60;60;63;65;65;64;64;63 09:50:21 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;57;61;57;68;60;59;58;60;57;58 09:50:21 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;85;85;85;86;85;81;93;101;100;102 09:50:21 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;130;139;137;142;123;134;135;140;225;173 09:50:21 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;165;165;164;165;163;164;163;163;162;167 09:50:21 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;64;64;64;65;63;64;63;63;63;64 09:50:21 INFO - PROCESS | 1651 | __end_tp_report 09:50:21 INFO - PROCESS | 1651 | __start_cc_report 09:50:21 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2148 09:50:21 INFO - PROCESS | 1651 | __end_cc_report 09:50:21 INFO - PROCESS | 1651 | __startTimestamp1495731021249__endTimestamp 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | ------- Summary: start ------- 09:50:21 INFO - PROCESS | 1651 | Number of tests: 14 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:114.20 Median:115.00 stddev:3.65 (3.2%) stddev-sans-first:0.71 09:50:21 INFO - PROCESS | 1651 | Values: 104.0 115.0 116.0 115.0 115.0 117.0 115.0 115.0 115.0 115.0 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:129.00 Median:139.50 stddev:17.40 (12.5%) stddev-sans-first:16.72 09:50:21 INFO - PROCESS | 1651 | Values: 150.0 140.0 139.0 141.0 110.0 110.0 110.0 107.0 146.0 137.0 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:1.49 (0.8%) stddev-sans-first:1.58 09:50:21 INFO - PROCESS | 1651 | Values: 185.0 184.0 183.0 187.0 186.0 185.0 182.0 184.0 185.0 186.0 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:66.50 Median:61.00 stddev:11.47 (18.8%) stddev-sans-first:10.50 09:50:21 INFO - PROCESS | 1651 | Values: 83.0 60.0 59.0 89.0 60.0 61.0 61.0 60.0 75.0 57.0 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:77.50 stddev:7.81 (10.1%) stddev-sans-first:7.25 09:50:21 INFO - PROCESS | 1651 | Values: 86.0 73.0 86.0 69.0 69.0 68.0 82.0 68.0 69.0 82.0 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:92.20 Median:87.00 stddev:15.45 (17.8%) stddev-sans-first:16.27 09:50:21 INFO - PROCESS | 1651 | Values: 87.0 87.0 87.0 86.0 87.0 87.0 87.0 87.0 91.0 136.0 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.99 (1.1%) stddev-sans-first:1.05 09:50:21 INFO - PROCESS | 1651 | Values: 94.0 94.0 94.0 95.0 93.0 93.0 95.0 95.0 92.0 94.0 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:1.18 (1.4%) stddev-sans-first:1.24 09:50:21 INFO - PROCESS | 1651 | Values: 86.0 84.0 86.0 87.0 86.0 84.0 85.0 84.0 86.0 87.0 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:63.50 stddev:2.00 (3.2%) stddev-sans-first:2.12 09:50:21 INFO - PROCESS | 1651 | Values: 63.0 60.0 60.0 60.0 63.0 65.0 65.0 64.0 64.0 63.0 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.50 stddev:3.31 (5.6%) stddev-sans-first:3.38 09:50:21 INFO - PROCESS | 1651 | Values: 57.0 61.0 57.0 68.0 60.0 59.0 58.0 60.0 57.0 58.0 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:85.00 stddev:7.96 (9.4%) stddev-sans-first:8.21 09:50:21 INFO - PROCESS | 1651 | Values: 85.0 85.0 85.0 86.0 85.0 81.0 93.0 101.0 100.0 102.0 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:139.50 stddev:30.13 (21.6%) stddev-sans-first:31.26 09:50:21 INFO - PROCESS | 1651 | Values: 130.0 139.0 137.0 142.0 123.0 134.0 135.0 140.0 225.0 173.0 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.10 Median:164.50 stddev:1.45 (0.9%) stddev-sans-first:1.50 09:50:21 INFO - PROCESS | 1651 | Values: 165.0 165.0 164.0 165.0 163.0 164.0 163.0 163.0 162.0 167.0 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:64.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 09:50:21 INFO - PROCESS | 1651 | Values: 64.0 64.0 64.0 65.0 63.0 64.0 63.0 63.0 63.0 64.0 09:50:21 INFO - PROCESS | 1651 | -------- Summary: end -------- 09:50:21 INFO - PROCESS | 1651 | 09:50:21 INFO - TEST-INFO | 1651: exit 0 09:50:21 INFO - TEST-OK | kraken | took 59068ms 09:50:21 INFO - SUITE-END | took 1177s 09:50:22 INFO - Completed test suite (00:19:37) 09:50:22 INFO - javascript benchmark 09:50:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7682.082058090892, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3406.9659303406966, 3419.9227243418322, 3426.366122267381, 3463.3246516929207, 3479.6694314040174, 5837.328707198673, 5865.618734782247, 5890.234269544959, 5893.528517718583, 5944.31640361358, 5904.498117660004, 5971.880562388749, 5976.790812321569, 5977.820665380045, 5985.281766188053, 5333.919991200125, 5367.194920761897, 5437.945620543784, 5446.836594902159, 5466.34403871536, 4755.571998520132, 5274.340707411574, 5280.947190528084, 5299.364076310848, 5316.043112239795, 4494.438195225597, 5384.542313903323, 5399.163129714901, 5440.782368705247, 5470.261514695525, 5428.294321738169, 5439.3200849893765, 5458.4541545845495, 5460.754266058037, 5462.6449280797015, 5272.499112584298, 5394.109971854639, 5396.973015134939, 5406.351237851483, 5474.206240095204, 5667.234923285345, 5723.027085395493, 5723.399043100456, 5781.0, 5940.70296485174, 6378.585391949508, 6414.807555773335, 6417.101605775195, 6429.58207716497, 6436.806895793134, 5428.809991650273, 6326.797908397436, 6387.329330420285, 6389.297177310492, 6415.6471394073305, 5869.735861886225, 5875.50058245054, 6027.246594175728, 6054.273487181523, 6113.57204995655, 5369.4630536946, 6346.936530634727, 6347.52393570484, 6351.618902865843, 6384.425401713868, 5396.325459317585, 6374.713137908805, 6398.360163983658, 6403.135576697086, 6409.583377080475, 6347.650879201596, 6349.063983935492, 6389.520785941073, 6407.455366293917, 6415.422611964852, 5573.972130139405, 6221.129041934133, 6386.07401926718, 6399.680015999182, 6413.807585772434, 6340.714667839959, 6365.236171659338, 6376.649284289316, 6382.234131904108, 6384.201974753156, 4287.421198138274, 4308.870733877989, 4909.607231421478, 4910.435299940481, 4955.430125535538, 4285.142971405731, 4370.453693288339, 4422.115576884636, 4422.579854913778, 4475.865724028285, 5293.285406470154, 5646.096624540054, 5688.800891968761, 5843.2403787507355, 5944.881102377928, 6374.490040796725, 6409.294977552466, 6415.967920160463, 6418.261899881573, 6419.51853610981, 5892.263467066617, 5898.64608123514, 5903.291605007426, 5937.643741375531, 5978.790742324085, 5373.38206106295, 5383.16560933056, 5450.863728406822, 5537.446255374431, 5554.555635549146, 6448.709808058648, 6481.481481481376, 6508.186476690414, 6568.638724869987, 6572.474202063728, 6377.362263773585, 6386.105945167773, 6391.073294372197, 6391.201099862517, 6410.102585637921, 6256.092866534418, 6474.482041436768, 6568.113304703995, 6570.967145164243, 6571.178602674666, 6235.875282494234, 6267.717952692231, 6275.592086514362, 6283.214598175228, 6313.05304204373, 6180.536459765445, 6220.160278362544, 6370.936290637034, 6387.489000880011, 6404.423601875854, 6195.349488303663, 6202.0, 6209.813705588839, 6214.658193799384, 6263.997760358321, 6137.539684523589, 6258.12386265914, 6265.686715664289, 6271.404216599416, 6271.905921411091, 5650.463205995424, 6126.639818174547, 6127.479164270909, 6175.382461753788, 6202.751889924352, 5330.9733451332495, 6322.114903913548, 6349.714262858275, 6401.679916004275, 6417.101605775102, 6238.220222472191, 6251.156093927261, 6275.309715931336, 6289.27673317556, 6304.180456540804], "value": 5806.255684417216, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.6233807772269, 787.2560430393279, 813.48750287319, 874.9256313213376, 887.6227603268609, 28316.0, 41641.0, 42210.15579688408, 42219.15561688764, 42398.0, 43211.78394108025, 43222.70331890041, 43291.917702057464, 43336.3499547507, 45012.0, 7614.505057171294, 9530.046995300467, 9706.272029597809, 9715.417074975525, 9718.805623887554, 7673.040869891263, 11648.242864213842, 11932.522699092026, 12091.818622720666, 12096.637100886988, 11895.583654572092, 11924.880751192508, 11927.045836333073, 11929.164958452911, 11948.70128246792, 11796.11529135314, 11854.170208085394, 11865.466054027589, 11867.643970680894, 11874.346910919896, 11496.31022138666, 11509.539618415254, 11551.942240288745, 11558.95969362753, 11563.53745850165, 41034.79482602598, 41315.38026929599, 41339.793301033606, 41383.58616413827, 41406.0, 3820.9044773880596, 3857.787821669807, 3910.393888947189, 3916.471276377681, 3975.383815508601, 28114.578281325797, 29196.124116276547, 29908.401831963456, 29983.550246746316, 30553.0, 3064.923376915595, 3122.2974830663147, 3185.729213016896, 3254.4142054430195, 3279.502119169798, 370747.0, 371326.0, 371682.0, 371826.0, 372172.0, 54168.729156354755, 54289.45710542844, 54306.45693543093, 54365.72817135968, 54507.0, 56019.0, 56026.0, 56041.0, 56065.719671401384, 56091.43908560944, 15060.774088388684, 15183.468578599695, 15189.0, 15189.16459594733, 15192.848071519364, 11299.209055366046, 11469.88530114705, 13053.28206948627, 13087.541936032194, 13104.868951310556, 9283.76790580241, 10768.515416806262, 10797.190210734227, 10798.784024319626, 12417.813732794015, 4442.034388485934, 5835.0, 5854.941450585439, 5861.9706901465215, 5880.647161170344, 939.2345238630494, 950.8811398575178, 981.3474039763478, 987.4026214140486, 1024.3802499487704, 29700.0, 29836.980906326426, 30002.84998574993, 30033.0, 30185.69814301873, 1057.471264367816, 1057.9949048404046, 1085.592902661502, 1088.2436706488913, 1099.7525556749667, 15426.922865385826, 15472.0, 15541.378344866078, 15565.688686226438, 15731.68536629261, 462783.0, 463600.0, 464923.0, 464980.0, 465388.0, 59339.406605933385, 59374.40625593775, 59518.0, 59582.0, 59864.40135598588, 1200.717831309624, 1218.7629556000666, 1471.4261438039387, 1509.3434356055152, 1513.341696362086, 27872.8850845972, 29935.275080906253, 30581.847090764404, 30590.62342194651, 30968.380632386776, 46454.53545464637, 47354.28968565543, 48846.0, 49115.75442122766, 49250.26124608062, 479854.0, 483759.0, 484042.5372350786, 484728.0, 485409.0, 24139.758602414455, 24531.63202551999, 29412.264693382836, 30270.57835373209, 30409.0, 38867.80566097151, 39592.80203598963, 40443.595564043986, 40469.0, 40513.79743101266, 26661.0, 28025.01912433055, 28172.43655126927, 28362.00732974336, 29256.82972681069, 27430.451390972466, 27901.209879011938, 28183.013594524095, 28276.717232828232, 29107.126786196448, 32611.0, 33147.50278745869, 33599.328013440085, 33636.49545256872, 33643.49534756932, 27997.720022799513, 28289.0, 28610.85694571514, 29003.41993160167, 29651.555226672048, 22176.334709958726, 23303.41741456477, 23379.76863408762, 23538.94074766674, 23615.0], "value": 19047.31416402962, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.4975054809504, 429.084328205727, 430.5823351349192, 437.73517022201565, 441.59152783675114, 19605.0, 20848.687269690967, 21490.355289341307, 21714.3485695429, 21821.23625673102, 21435.678464823002, 22412.32763017108, 22873.885630571865, 22926.197583084595, 23194.536109277848, 6636.137302150713, 6704.765333213339, 6920.515563910529, 6957.79126626202, 6976.337247961461, 6762.763303284386, 6899.896501552481, 6906.033155358254, 6921.5847049176955, 6938.514303998722, 6553.180852393451, 6791.728330866759, 6896.689648965808, 6956.79129626112, 6969.965150174242, 6050.54620903434, 6733.158355205599, 6788.660566971632, 6790.151231096113, 6809.216940051908, 6025.668588227646, 6621.437177839889, 6678.265390807056, 6686.93313066868, 6687.966560167217, 26603.733962660513, 26897.193084207505, 26933.192004239903, 27032.729672703415, 27287.727122728917, 2967.406518696248, 2978.731914127717, 2979.7318241358166, 2991.6110905582136, 3006.8947586834356, 15862.682746345125, 16604.25280862364, 16731.83268167315, 16757.41349052777, 17428.12859357027, 2100.5588826346484, 2165.621016322137, 2166.295953815016, 2167.729033870766, 2168.2194410012385, 122315.0, 127183.36408317991, 127745.72254277431, 127952.0, 130645.34677326647, 19766.70349944752, 20799.480012999495, 20904.581908361746, 20950.790492095188, 20982.2656207032, 32723.0, 33768.66231337655, 34179.65820341815, 34236.0, 34304.82847585746, 10593.89406105929, 10595.311304765166, 10840.891591084146, 10940.781184376268, 10945.616903408343, 8813.735587932373, 8824.823503529893, 8853.070427605138, 9011.188992990663, 9036.909630903738, 8809.295256379473, 9296.674616388394, 10049.447280399501, 10136.0, 10140.340877842917, 3507.9649203508147, 4088.1619268049976, 4095.8361665533043, 4118.402831589403, 4149.7095203335475, 903.746950853762, 926.1710768861831, 928.1831987850783, 936.4615346175976, 963.5326866469757, 17142.657146857244, 21150.048247828883, 22326.0, 22419.663705044437, 22589.0, 969.2924165359186, 969.3989726369696, 986.8914419413859, 990.8860481044773, 996.7557948302567, 9535.141837234682, 11397.373144477133, 11686.0, 11773.52905883755, 11806.291622502677, 724376.130739664, 734455.0, 754214.0, 768007.0, 782502.0, 116875.0, 120292.39853800675, 123848.0, 124150.0, 124409.9999999982, 1210.9636710898687, 1259.7165637731437, 1303.9022073344727, 1307.378994977397, 1310.1156719214682, 18739.625207496047, 18881.71677424867, 19346.613067738286, 19477.902610486857, 19480.902595486932, 48615.27077093776, 50006.0, 64706.0, 64842.67578662076, 64976.70046598947, 89665.55167224123, 92998.53500732454, 95754.0, 96179.51910240404, 97529.51235243779, 7084.149902011792, 10119.241056920908, 10797.676069717922, 11145.554177832795, 11155.330680159217, 11967.64097077089, 12091.57679481214, 12266.632001039983, 12508.374581270718, 12550.435230414836, 10637.468126593794, 10676.62631807883, 10720.892791071989, 10870.456477176267, 10955.452227388758, 9946.751331216778, 10023.498825058572, 10029.097381235724, 10290.176785856964, 10317.226208034277, 10812.891871081189, 10919.39943303126, 10991.560337586407, 11150.330980141218, 11174.497147628213, 10853.0, 10916.727081823017, 10957.56169753201, 11093.5562577496, 11123.83314250303, 6000.939990600038, 10567.313124646873, 10713.732156696145, 10719.0, 10765.246432749633], "value": 11418.776024170373, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.836939567252, 1592.984070159298, 1614.6609212065462, 1619.927103280353, 1641.2860405723509, 9249.260059195265, 9666.661666841663, 10983.121350291987, 11108.0, 11533.884661153386, 11187.32876027439, 11270.887291127086, 11290.830637540443, 11297.322160670366, 11313.208075434723, 2276.4422716434497, 2307.492351682627, 2310.0759696121518, 2330.6737056811976, 2331.7901388875, 1959.6472634925708, 2040.5918816236738, 2041.2345370486069, 2052.538178909741, 2053.7638171610306, 1998.1607724755565, 2244.45010972312, 2303.8156947444163, 2310.1452462588863, 2321.5472982768365, 2310.792028717415, 2324.4421338878547, 2325.6395258734924, 2327.3134425344397, 2328.6157783965627, 2258.9887050564807, 2278.7037685100995, 2293.6788849560908, 2297.425643589103, 2297.8046866016243, 9212.309076819265, 11200.439978001148, 11260.54957801687, 11271.26736762116, 11281.92821681939, 5383.596230282745, 5551.8889622207325, 5552.139418390116, 5564.554835613141, 5590.468905453975, 3136.5582316451623, 3214.976091158821, 3269.591301087364, 3321.8173000484962, 3350.363430948112, 1101.713554475842, 1110.4614262082882, 1128.7911736328806, 1145.5532342386393, 1148.5979907032556, 15462.0, 15653.0, 15731.213439327988, 15755.606109847116, 15787.28957196929, 4269.48766148058, 4466.441694788152, 4514.977425112854, 4526.20791361517, 4573.131105090022, 12661.873381266254, 12669.429875655618, 12676.176048556814, 12686.0, 12688.429020694091, 5453.291072160673, 5468.781248750085, 5471.987682278791, 5499.642523235977, 5502.532284755841, 4874.902501949941, 4971.304017437562, 4974.552290293891, 5037.319961805183, 5139.203423469368, 2845.231787417386, 3223.7098661120613, 3477.94783078254, 3714.8483969969398, 3763.5483741951143, 946.990530094704, 956.2111258212003, 1003.5935446144323, 1025.8512515685334, 1027.753339198602, 800.6917336825364, 824.056455358623, 835.6156168162713, 847.5042100371352, 878.7012415778665, 6647.60114393138, 6782.0, 6811.352921472452, 6913.10129683148, 7018.613976231356, 942.3309450290234, 1012.6000229909145, 1051.6161601015726, 1058.9311694739818, 1077.0844781935448, 4375.409319741858, 4427.203103441411, 4509.909801803946, 4588.816447342136, 4591.288350305708, 13532.729345413036, 13837.169769813843, 13979.580612581638, 14016.0, 14032.36854341557, 13075.476980920657, 13423.932880335537, 13861.86138138626, 13886.0, 13893.513727019708, 9860.605575776888, 10148.289619726696, 10153.593856245816, 10164.237682173718, 10178.033086856736, 6716.328367163244, 6961.721531138698, 7138.750143744944, 7142.857142857218, 7227.530210536298, 11057.944710276397, 11962.641120766391, 12584.119111662461, 12630.0, 12676.87323126757, 11141.498632561392, 12400.945919596936, 12488.12583119173, 12621.116521843383, 12694.619161425171, 9317.021712720067, 9351.53242337894, 9525.095115963972, 9560.426374417557, 9575.760605984628, 10346.878889827718, 10358.5338659762, 10450.634227801991, 10457.633982810565, 10474.214433917334, 8427.95786021066, 8654.30765538768, 8673.869891951499, 8711.607977640895, 8877.911220887709, 8765.298776097768, 8792.0, 8801.691940782042, 8871.979722331756, 8896.777580560538, 7921.247481489249, 8071.919280807117, 8084.070331911906, 8088.312493438124, 8093.514389136671, 4613.838515652071, 5954.553408494294, 5974.940250597612, 6076.361981992004, 6152.753889844356, 5021.72380519075, 5612.681019960224, 6156.292026416841, 6178.135061091541, 6218.657973811484], "value": 5046.910215065525, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.8664041063369, 381.98011309802826, 386.1870762045895, 391.1785250972956, 391.27223364541965, 4863.051704917542, 4939.456659767432, 5182.559482443992, 5234.7644356003975, 5268.31511903452, 4561.247394179956, 4887.657863949525, 4929.450795427296, 5082.847514574569, 5167.9483205168035, 3886.961130388688, 4011.2980228460046, 4018.7789671568053, 4055.81748821303, 4088.853351990214, 3576.3204991051616, 3818.639597154514, 4044.9797751011206, 4047.919041619166, 4063.2279866825356, 4054.9391759123637, 4069.9796501017454, 4082.4896887889013, 4102.466679331683, 4255.319148936171, 3511.7190624750215, 3525.2068284636007, 3756.8309426075893, 3936.1266275618814, 3942.0, 3460.688538031564, 3854.7879866607327, 3874.1476875087437, 3886.261610294035, 3906.8046597670286, 3849.826757795906, 4493.842955381435, 4907.601333619901, 4968.962725282653, 4969.304297398331, 4613.354130421743, 4733.83431579893, 4891.6736619099, 4923.58149557288, 4949.698390248515, 4327.870163895057, 4506.526814684476, 4965.379327584052, 5010.2735103409805, 5332.866678333073, 3976.1053762903402, 4209.831606735727, 4343.2399330117105, 4345.652347812167, 4418.961544037149, 4874.026019462052, 5357.0, 5645.515972971879, 5785.71071446426, 5849.7660093596205, 4070.776107314067, 4155.688323375759, 4301.78491075445, 4377.846775362847, 4439.667024973075, 4480.484744254452, 4486.468666646645, 4490.991036005884, 4507.278835386388, 4509.751963642031, 4813.205821039559, 4850.08421673437, 4879.121758083508, 4900.509949005071, 4912.680675756064, 4713.434387873409, 5146.511081447256, 5332.093544097513, 5381.408045115034, 5383.703896285742, 1394.4939465377367, 1408.6476982097097, 1423.9391653218418, 1427.9214643194516, 1517.6205948512873, 1088.9509972051276, 1158.8609366876028, 1161.872194058653, 1168.661088284405, 1174.3188950408742, 4361.629261512743, 4440.844570440084, 4467.46034916701, 4610.953890461053, 4672.504798464527, 4987.975060124676, 5373.2387328869945, 5615.466530679579, 5657.19968615538, 5667.603267771216, 4590.311453282035, 4661.766911654404, 4851.636127290467, 4914.33656456381, 4941.085899108676, 2550.0182429764304, 2814.8311101333984, 2858.6426696662916, 2859.7140285971236, 2879.7408233259107, 5644.911382788395, 5776.971115144397, 5784.768609255582, 5795.0, 5821.126830975303, 5209.921851172236, 5482.698451585201, 5493.890122197614, 5794.739236734357, 5832.504237139806, 4978.47725988766, 5612.410696876851, 5623.297087864017, 5753.712314384264, 5847.0, 4740.715557066587, 5317.867053323698, 5392.7573259202645, 5612.249997501277, 5625.493705566519, 4726.432828060655, 4914.483979182278, 5062.493750624908, 5245.344331958505, 5265.578753699771, 5791.305043394819, 5796.913046304393, 5807.0, 5854.5609079320075, 5856.443637854397, 3858.8649397272084, 4440.777961101868, 4504.527024662363, 4562.338460923082, 4579.519150489151, 4989.775460104232, 5138.948610513997, 5416.1688570291035, 5537.2524709163745, 5586.329640443173, 4640.721556706609, 5412.621116521806, 5440.238366628754, 5452.0, 5455.972720136374, 4488.506264310989, 5407.270018547446, 5457.672539647634, 5460.399356070909, 5501.259143782267, 4051.858184963512, 4505.341848309382, 5301.773255232734, 5311.468853114658, 5355.651882627617, 5406.567474601944, 5436.592255580927, 5440.7551660174595, 5468.179773034077, 5473.698946557818, 4348.2825333819565, 4394.824207031683, 4803.5916947058495, 4931.605471562339, 5383.084875571084], "value": 4117.690278116969, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [752.7854561449981, 766.2031487253257, 781.5623250979452, 826.7437094500705, 840.8192238668687, 15206.391744330227, 16574.50276491704, 16767.24547395367, 16819.831801681994, 16828.411005614806, 12133.817992730115, 14220.573382798506, 14360.281985900716, 14374.640633984156, 14398.712025759478, 972.3873959405582, 994.9602015919354, 996.6960077176458, 997.147438939707, 997.2321312589302, 810.9553974531399, 986.4130842148945, 993.9950300248489, 1005.8541511480831, 1008.1380419741125, 1041.0682439216935, 1042.9269951103427, 1056.6724315462193, 1060.7401186709267, 1061.2995423020807, 1033.7415646088477, 1033.7725700345911, 1035.6582327831836, 1037.1599004806094, 1038.1331588123926, 771.4522688890838, 771.6681826814468, 772.9304362607336, 773.8336257704565, 774.1600363605495, 16055.438059667973, 16217.270222840001, 16312.673746525003, 16904.070276134808, 17108.973461592344, 13767.31163441824, 14390.136591804525, 14868.182249976247, 15436.845631543765, 15599.532014039483, 11695.047323152305, 11734.23727457721, 11932.522699092026, 12005.0, 12424.378781061001, 3764.7552909061096, 3824.961750382516, 3849.4225866120028, 4002.3796311571464, 4004.6395824375945, 91226.5438672809, 95553.0, 104952.0, 106015.46992265066, 106248.46875765572, 11592.536298547964, 11601.245919015406, 11823.231489953125, 11875.109366797526, 12443.377831108408, 25071.247862564152, 25154.24537263885, 25168.74831251664, 25191.748082519305, 25249.11628093008, 8179.959100204461, 8329.833403331899, 8433.40966132377, 8452.8732069019, 8624.827503450022, 4226.450561426995, 4413.007073408522, 4492.528284530143, 4499.100179963955, 4674.742889141062, 2902.3469719313407, 3452.8100954447746, 3457.9654203457644, 3552.182997910495, 3663.725220608465, 684.7295318349323, 687.7730348984823, 690.6650274616807, 702.550367764631, 704.5807744392079, 664.2095905871997, 679.212113947828, 691.8132104331802, 702.5328156775789, 724.1744411371042, 10080.747981300527, 10258.17934565233, 10401.84397234042, 10533.473326333653, 10533.631322903662, 677.6577828196828, 705.7459314646723, 706.9823254418578, 711.959027650386, 741.4328039050131, 4974.228994505857, 5162.174052151638, 5170.560502357266, 5186.429492755794, 5194.584433245407, 87714.0, 103959.0, 106415.86730615476, 107114.9288507105, 109453.0, 80720.19279807126, 86746.1325386738, 87053.0, 89325.55337223402, 91283.0, 82154.17845821584, 82992.17007829844, 83227.5838620803, 85223.5738821302, 86309.0, 9607.279454041114, 9849.950750246202, 9907.405555666683, 9915.504224788876, 9931.801363972825, 33873.97260273973, 51654.0, 51989.7400512995, 52129.0, 54108.45891541192, 62222.377776221656, 71297.0, 71439.0, 75068.0, 75937.0, 13045.152065115737, 13064.80402793978, 13167.93416032952, 13272.27002514871, 13551.0, 12039.217450865666, 14320.856791431952, 14535.927320363331, 14831.92584037073, 15906.204689765234, 12771.680707982376, 13560.45758169662, 13659.872072475988, 13793.793093103412, 14129.010969232055, 13785.310734463437, 13846.93076534611, 14304.928475357556, 14499.78250326267, 14653.142637185647, 13432.462701491831, 14277.214753188675, 14395.496157634432, 14406.495772648326, 14637.26813659334, 9053.0, 9563.187129093827, 9737.269704772028, 9816.607335706492, 9986.300958932805, 8771.982384133778, 9403.34176607631, 10015.749606259902, 10716.785664286515, 11164.0], "value": 7830.717152571886, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1429.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 116.0, 115.0, 115.0, 117.0, 115.0, 115.0, 115.0, 115.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 140.0, 139.0, 141.0, 110.0, 110.0, 110.0, 107.0, 146.0, 137.0], "value": 129.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 183.0, 187.0, 186.0, 185.0, 182.0, 184.0, 185.0, 186.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 59.0, 89.0, 60.0, 61.0, 61.0, 60.0, 75.0, 57.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 73.0, 86.0, 69.0, 69.0, 68.0, 82.0, 68.0, 69.0, 82.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 86.0, 87.0, 87.0, 87.0, 87.0, 91.0, 136.0], "value": 92.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 93.0, 93.0, 95.0, 95.0, 92.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 87.0, 86.0, 84.0, 85.0, 84.0, 86.0, 87.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 60.0, 60.0, 63.0, 65.0, 65.0, 64.0, 64.0, 63.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 57.0, 68.0, 60.0, 59.0, 58.0, 60.0, 57.0, 58.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 86.0, 85.0, 81.0, 93.0, 101.0, 100.0, 102.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 139.0, 137.0, 142.0, 123.0, 134.0, 135.0, 140.0, 225.0, 173.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 164.0, 165.0, 163.0, 164.0, 163.0, 163.0, 162.0, 167.0], "value": 164.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 63.0, 64.0, 63.0, 63.0, 63.0, 64.0], "value": 63.7, "unit": "score"}]}]} 09:50:22 INFO - Return code: 0 09:50:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:50:22 INFO - # TBPL SUCCESS # 09:50:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:50:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:50:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:50:22 INFO - 2017-05-25 09:50:22.576 screenresolution[1655:10128] starting screenresolution argv=screenresolution get 09:50:22 INFO - 2017-05-25 09:50:22.578 screenresolution[1655:10128] Display 0: 1600x1200x32@60 09:50:22 INFO - 2017-05-25 09:50:22.584 screenresolution[1656:10131] starting screenresolution argv=screenresolution list 09:50:22 INFO - Available Modes on Display 0 09:50:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:50:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:50:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:50:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:50:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:50:22 INFO - Intel Iris: 09:50:22 INFO - Chipset Model: Intel Iris 09:50:22 INFO - Type: GPU 09:50:22 INFO - Bus: Built-In 09:50:22 INFO - VRAM (Dynamic, Max): 1536 MB 09:50:22 INFO - Vendor: Intel (0x8086) 09:50:22 INFO - Device ID: 0x0a2e 09:50:22 INFO - Revision ID: 0x0009 09:50:22 INFO - Displays: 09:50:22 INFO - Display: 09:50:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:50:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:50:22 INFO - Display Serial Number: 1600x1200 60 09:50:22 INFO - Main Display: Yes 09:50:22 INFO - Mirror: Off 09:50:22 INFO - Online: Yes 09:50:22 INFO - Rotation: Supported 09:50:22 INFO - Return code: 0 09:50:22 INFO - Running post-action listener: _resource_record_post_action 09:50:22 INFO - [mozharness: 2017-05-25 16:50:22.821680Z] Finished run-tests step (success) 09:50:22 INFO - Running post-run listener: _resource_record_post_run 09:50:22 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 39945216; Write bytes: 510211072; Read time: 258; Write time: 1570 09:50:22 INFO - TinderboxPrint: CPU usage
53.7% 09:50:22 INFO - TinderboxPrint: I/O read bytes / time
39,945,216 / 258 09:50:22 INFO - TinderboxPrint: I/O write bytes / time
510,211,072 / 1,570 09:50:22 INFO - TinderboxPrint: CPU idle
2,218.4 (46.3%) 09:50:22 INFO - TinderboxPrint: CPU system
951.7 (19.9%) 09:50:22 INFO - TinderboxPrint: CPU user
1,623.6 (33.9%) 09:50:22 INFO - TinderboxPrint: Swap in / out
247,123,968 / 0 09:50:22 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 204286464; Write bytes: 202061312; Read time: 15651; Write time: 417 09:50:22 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 37815296; Write bytes: 297270784; Read time: 237; Write time: 1117 09:50:23 INFO - Running post-run listener: _upload_blobber_files 09:50:23 INFO - Blob upload gear active. 09:50:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:50:23 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 09:50:23 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'] 09:50:23 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 09:50:23 INFO - (blobuploader) - INFO - Open directory for files ... 09:50:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:50:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:50:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:24 INFO - (blobuploader) - INFO - Done attempting. 09:50:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:50:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:50:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:25 INFO - (blobuploader) - INFO - Done attempting. 09:50:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:50:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:50:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:27 INFO - (blobuploader) - INFO - Done attempting. 09:50:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:50:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:50:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:29 INFO - (blobuploader) - INFO - Done attempting. 09:50:29 INFO - (blobuploader) - INFO - Iteration through files over. 09:50:29 INFO - Return code: 0 09:50:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:50:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:50:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/66263cda049f2153f4fb98aff335cc041c4f74058458508802b5a49116a1551be1fbfc859a16dfbd5da1d28c231a0c115e0c47420464b1b5ff49230b4b96b204", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62cd266903ba10d3007ba51ee37f38d43697fe1f19b290109f5c3c168e020a597a4591bba034cda5706293946c612d9f05a98faa19cba36a2bf204ac4cf41e26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de66b91cbc199fd1bde8d7f69573e50d130e03455f9af622f5abe8d57c9778831e2a3a16ecd3d999a89feaad093ae02d0a9fbee65117b32ca43cb25365eb6a63", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78081218f963a619ea6e2af7d4aa69902fb1e04c58ade85b79bc4be4042d0e15ace212a72b1021fa568ec429360d439ebe3314752c216da2a7bf45f6e27d17d8"} 09:50:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:50:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:50:29 INFO - Contents: 09:50:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/66263cda049f2153f4fb98aff335cc041c4f74058458508802b5a49116a1551be1fbfc859a16dfbd5da1d28c231a0c115e0c47420464b1b5ff49230b4b96b204", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62cd266903ba10d3007ba51ee37f38d43697fe1f19b290109f5c3c168e020a597a4591bba034cda5706293946c612d9f05a98faa19cba36a2bf204ac4cf41e26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de66b91cbc199fd1bde8d7f69573e50d130e03455f9af622f5abe8d57c9778831e2a3a16ecd3d999a89feaad093ae02d0a9fbee65117b32ca43cb25365eb6a63", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78081218f963a619ea6e2af7d4aa69902fb1e04c58ade85b79bc4be4042d0e15ace212a72b1021fa568ec429360d439ebe3314752c216da2a7bf45f6e27d17d8"} 09:50:29 INFO - Running post-run listener: copy_logs_to_upload_dir 09:50:29 INFO - Copying logs to upload dir... 09:50:29 INFO - mkdir: /builds/slave/test/build/upload/logs 09:50:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.697864 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-25 09:50:29.402074) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 09:50:29.405817) ========= 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.lanVmrnZc2/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.6iOdqTlBN1/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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/66263cda049f2153f4fb98aff335cc041c4f74058458508802b5a49116a1551be1fbfc859a16dfbd5da1d28c231a0c115e0c47420464b1b5ff49230b4b96b204", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62cd266903ba10d3007ba51ee37f38d43697fe1f19b290109f5c3c168e020a597a4591bba034cda5706293946c612d9f05a98faa19cba36a2bf204ac4cf41e26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de66b91cbc199fd1bde8d7f69573e50d130e03455f9af622f5abe8d57c9778831e2a3a16ecd3d999a89feaad093ae02d0a9fbee65117b32ca43cb25365eb6a63", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78081218f963a619ea6e2af7d4aa69902fb1e04c58ade85b79bc4be4042d0e15ace212a72b1021fa568ec429360d439ebe3314752c216da2a7bf45f6e27d17d8"} build_url:https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011147 build_url: 'https://queue.taskcluster.net/v1/task/CGpH_5-JS2ei-sVXuUFTlg/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/66263cda049f2153f4fb98aff335cc041c4f74058458508802b5a49116a1551be1fbfc859a16dfbd5da1d28c231a0c115e0c47420464b1b5ff49230b4b96b204", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62cd266903ba10d3007ba51ee37f38d43697fe1f19b290109f5c3c168e020a597a4591bba034cda5706293946c612d9f05a98faa19cba36a2bf204ac4cf41e26", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de66b91cbc199fd1bde8d7f69573e50d130e03455f9af622f5abe8d57c9778831e2a3a16ecd3d999a89feaad093ae02d0a9fbee65117b32ca43cb25365eb6a63", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78081218f963a619ea6e2af7d4aa69902fb1e04c58ade85b79bc4be4042d0e15ace212a72b1021fa568ec429360d439ebe3314752c216da2a7bf45f6e27d17d8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 09:50:29.441135) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:50:29.441650) ========= 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.lanVmrnZc2/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.6iOdqTlBN1/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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.005073 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:50:29.492150) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 09:50:29.492610) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 09:50:29.493169) ========= ========= Total master_lag: 0.35 =========