builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0289 starttime: 1496368973.11 results: success (0) revision: 1e229cf8933b08191e4243a6b9dbb3c821db60ab ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 19:02:53.110394) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 19:02:53.111061) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 19:02:53.111540) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 19:02:53.138414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 19:02:53.138801) ========= 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.7MedGtEOkO/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.6gObjV8OxF/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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-06-01 19:02:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 171M=0s 2017-06-01 19:02:53 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137904 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 19:02:53.302113) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 19:02:53.302447) ========= 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.7MedGtEOkO/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.6gObjV8OxF/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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.032465 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 19:02:53.357112) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-01 19:02:53.357496) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1e229cf8933b08191e4243a6b9dbb3c821db60ab --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 1e229cf8933b08191e4243a6b9dbb3c821db60ab --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7MedGtEOkO/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.6gObjV8OxF/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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-06-01 19:02:53,456 truncating revision to first 12 chars 2017-06-01 19:02:53,457 Setting DEBUG logging. 2017-06-01 19:02:53,457 attempt 1/10 2017-06-01 19:02:53,457 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1e229cf8933b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 19:02:55,639 unpacking tar archive at: mozilla-central-1e229cf8933b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.453421 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-01 19:02:55.833649) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 19:02:55.833964) ========= script_repo_revision: 1e229cf8933b08191e4243a6b9dbb3c821db60ab ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 19:02:55.834323) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 19:02:55.834600) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 19:02:55.853949) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-01 19:02:55.854347) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7MedGtEOkO/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.6gObjV8OxF/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 19:02:56 INFO - MultiFileLogger online at 20170601 19:02:56 in /builds/slave/test 19:02:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:02:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:02:56 INFO - {'append_to_log': False, 19:02:56 INFO - 'base_work_dir': '/builds/slave/test', 19:02:56 INFO - 'blob_upload_branch': 'Firefox', 19:02:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:02:56 INFO - 'branch': 'Firefox', 19:02:56 INFO - 'buildbot_json_path': 'buildprops.json', 19:02:56 INFO - 'config_files': ('talos/mac_config.py',), 19:02:56 INFO - 'default_actions': ('clobber', 19:02:56 INFO - 'read-buildbot-config', 19:02:56 INFO - 'download-and-extract', 19:02:56 INFO - 'populate-webroot', 19:02:56 INFO - 'create-virtualenv', 19:02:56 INFO - 'install', 19:02:56 INFO - 'setup-mitmproxy', 19:02:56 INFO - 'run-tests'), 19:02:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:02:56 INFO - 'download_minidump_stackwalk': True, 19:02:56 INFO - 'download_symbols': 'ondemand', 19:02:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:02:56 INFO - 'tooltool.py': '/tools/tooltool.py', 19:02:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:02:56 INFO - '/tools/misc-python/virtualenv.py')}, 19:02:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:02:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:02:56 INFO - 'gecko_profile': False, 19:02:56 INFO - 'gecko_profile_interval': 0, 19:02:56 INFO - 'installer_path': 'installer.exe', 19:02:56 INFO - 'log_level': 'info', 19:02:56 INFO - 'log_name': 'talos', 19:02:56 INFO - 'log_to_console': True, 19:02:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:02:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:02:56 INFO - 'opt_config_files': (), 19:02:56 INFO - 'pip_index': False, 19:02:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:02:56 INFO - 'cmd': ('bash', 19:02:56 INFO - '-c', 19:02:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:02:56 INFO - 'enabled': True, 19:02:56 INFO - 'halt_on_failure': False, 19:02:56 INFO - 'name': 'check_screen_resolution'},), 19:02:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:02:56 INFO - 'cmd': ('bash', 19:02:56 INFO - '-c', 19:02:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:02:56 INFO - 'enabled': True, 19:02:56 INFO - 'halt_on_failure': False, 19:02:56 INFO - 'name': 'check_screen_resolution'},), 19:02:56 INFO - 'run_cmd_checks_enabled': True, 19:02:56 INFO - 'suite': 'dromaeojs-e10s', 19:02:56 INFO - 'system_bits': '32', 19:02:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:02:56 INFO - 'title': 't-yosemite-r7-0289', 19:02:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:02:56 INFO - 'use_talos_json': True, 19:02:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:02:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:02:56 INFO - 'work_dir': 'build'} 19:02:56 INFO - [mozharness: 2017-06-02 02:02:56.059290Z] Running clobber step. 19:02:56 INFO - Running pre-action listener: _resource_record_pre_action 19:02:56 INFO - Running main action method: clobber 19:02:56 INFO - rmtree: /builds/slave/test/build 19:02:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:02:58 INFO - Running post-action listener: _resource_record_post_action 19:02:58 INFO - [mozharness: 2017-06-02 02:02:58.534853Z] Finished clobber step (success) 19:02:58 INFO - [mozharness: 2017-06-02 02:02:58.535008Z] Running read-buildbot-config step. 19:02:58 INFO - Running pre-action listener: _resource_record_pre_action 19:02:58 INFO - Running main action method: read_buildbot_config 19:02:58 INFO - Using buildbot properties: 19:02:58 INFO - { 19:02:58 INFO - "project": "", 19:02:58 INFO - "product": "firefox", 19:02:58 INFO - "who": "kwierso@gmail.com", 19:02:58 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 19:02:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:02:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 19:02:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:02:58 INFO - "stage_platform": "macosx64", 19:02:58 INFO - "basedir": "/builds/slave/test", 19:02:58 INFO - "buildnumber": 10, 19:02:58 INFO - "platform": "yosemite_r7", 19:02:58 INFO - "parent_task_id": "fOzFZKeISn-vVnhJX0jloQ", 19:02:58 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 19:02:58 INFO - "slavebuilddir": "test", 19:02:58 INFO - "taskId": "BKH45VY6QbCvk7TDoOH5GA", 19:02:58 INFO - "branch": "mozilla-central", 19:02:58 INFO - "script_repo_revision": "production", 19:02:58 INFO - "revision": "1e229cf8933b08191e4243a6b9dbb3c821db60ab", 19:02:58 INFO - "slavename": "t-yosemite-r7-0289", 19:02:58 INFO - "repo_path": "mozilla-central" 19:02:58 INFO - } 19:02:58 INFO - Finding installer, test and symbols from parent task. 19:02:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BKH45VY6QbCvk7TDoOH5GA'}, attempt #1 19:02:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BKH45VY6QbCvk7TDoOH5GA'}, attempt #1 19:02:59 INFO - Task dependencies: GdeLjiUPSiWY3Xu0_bmPAw fOzFZKeISn-vVnhJX0jloQ 19:02:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ'}, attempt #1 19:03:00 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:03:00 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/test_packages.json 19:03:00 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/target.crashreporter-symbols.zip 19:03:00 INFO - Running post-action listener: _resource_record_post_action 19:03:00 INFO - [mozharness: 2017-06-02 02:03:00.246113Z] Finished read-buildbot-config step (success) 19:03:00 INFO - [mozharness: 2017-06-02 02:03:00.246229Z] Running download-and-extract step. 19:03:00 INFO - Running pre-action listener: _resource_record_pre_action 19:03:00 INFO - Running main action method: download_and_extract 19:03:00 INFO - mkdir: /builds/slave/test/build/tests 19:03: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')]} 19:03:00 INFO - trying https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 19:03:00 INFO - Downloading https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:03:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 19:03:01 INFO - Downloaded 1325 bytes. 19:03:01 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:03:01 INFO - Using the following test package requirements: 19:03:01 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:01 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 19:03:01 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:03:01 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:01 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 19:03:01 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:01 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 19:03:01 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:01 INFO - u'jsshell-mac.zip'], 19:03:01 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:01 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 19:03:01 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:03:01 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:01 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 19:03:01 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:01 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 19:03:01 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:01 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 19:03:01 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:03:01 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 19:03:01 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:03:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:03:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:03:01 INFO - Fetch https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:03:02 INFO - Content-Length response header: 57625096 19:03:02 INFO - Bytes received: 57625096 19:03:20 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:03:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:03:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:03:20 INFO - Fetch https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:03:23 INFO - Content-Length response header: 57625096 19:03:23 INFO - Bytes received: 57625096 19:03:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 19:03:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:03:40 INFO - Fetch https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 19:03:41 INFO - Content-Length response header: 14447425 19:03:41 INFO - Bytes received: 14447425 19:03:42 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')]} 19:03:42 INFO - trying https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:03:42 INFO - Downloading https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 19:03:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 19:03:44 INFO - Downloaded 67911351 bytes. 19:03:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:03:44 INFO - mkdir: /builds/slave/test/properties 19:03:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:03:44 INFO - Writing to file /builds/slave/test/properties/build_url 19:03:44 INFO - Contents: 19:03:44 INFO - build_url:https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:03:44 INFO - Running post-action listener: _resource_record_post_action 19:03:44 INFO - Running post-action listener: set_extra_try_arguments 19:03:44 INFO - [mozharness: 2017-06-02 02:03:44.329097Z] Finished download-and-extract step (success) 19:03:44 INFO - [mozharness: 2017-06-02 02:03:44.329249Z] Running populate-webroot step. 19:03:44 INFO - Running pre-action listener: _resource_record_pre_action 19:03:44 INFO - Running main action method: populate_webroot 19:03:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:03:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:03:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:03:44 INFO - '--tppagecycles', 19:03:44 INFO - '7'], 19:03:44 INFO - 'tests': ['tsvgm']}}, 19:03:44 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:03:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:03:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:03:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:03:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:03:44 INFO - 'talos_options': ['--disable-e10s'], 19:03:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:03:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:03:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:03:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:03:44 INFO - 'talos_options': ['--disable-e10s'], 19:03:44 INFO - 'tests': ['damp', 'tps']}, 19:03:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:03:44 INFO - 'tests': ['damp', 'tps']}, 19:03:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:03:44 INFO - 'tests': ['dromaeo_dom']}, 19:03:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:03:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:03:44 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 19:03:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:03:44 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:03:44 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:03:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:03:44 INFO - 'tests': ['a11yr', 19:03:44 INFO - 'ts_paint', 19:03:44 INFO - 'tpaint', 19:03:44 INFO - 'sessionrestore', 19:03:44 INFO - 'sessionrestore_no_auto_restore', 19:03:44 INFO - 'tabpaint']}, 19:03:44 INFO - 'other-e10s': {'tests': ['a11yr', 19:03:44 INFO - 'ts_paint', 19:03:44 INFO - 'tpaint', 19:03:44 INFO - 'sessionrestore', 19:03:44 INFO - 'sessionrestore_no_auto_restore', 19:03:44 INFO - 'tabpaint']}, 19:03:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:03:44 INFO - 'ts_paint', 19:03:44 INFO - 'tpaint', 19:03:44 INFO - 'sessionrestore', 19:03:44 INFO - 'sessionrestore_no_auto_restore', 19:03:44 INFO - 'tabpaint']}, 19:03:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:03:44 INFO - 'ts_paint', 19:03:44 INFO - 'tpaint', 19:03:44 INFO - 'sessionrestore', 19:03:44 INFO - 'sessionrestore_no_auto_restore', 19:03:44 INFO - 'tabpaint']}, 19:03:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:03:44 INFO - 'tests': ['a11yr', 19:03:44 INFO - 'ts_paint', 19:03:44 INFO - 'tpaint', 19:03:44 INFO - 'sessionrestore', 19:03:44 INFO - 'sessionrestore_no_auto_restore', 19:03:44 INFO - 'tabpaint']}, 19:03:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:03:44 INFO - 'tests': ['a11yr', 19:03:44 INFO - 'ts_paint', 19:03:44 INFO - 'tpaint', 19:03:44 INFO - 'sessionrestore', 19:03:44 INFO - 'sessionrestore_no_auto_restore', 19:03:44 INFO - 'tabpaint']}, 19:03:44 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 19:03:44 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 19:03:44 INFO - 'bloom_basic_ref']}, 19:03:44 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 19:03:44 INFO - 'talos_options': ['--mitmproxy', 19:03:44 INFO - 'mitmproxy-recording-1.mp', 19:03:44 INFO - '--firstNonBlankPaint'], 19:03:44 INFO - 'tests': ['Quantum_1']}, 19:03:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:03:44 INFO - 'tests': ['tsvgx', 19:03:44 INFO - 'tsvgr_opacity', 19:03:44 INFO - 'tart', 19:03:44 INFO - 'tscrollx', 19:03:44 INFO - 'cart', 19:03:44 INFO - 'tsvg_static']}, 19:03:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:03:44 INFO - 'tsvgr_opacity', 19:03:44 INFO - 'tart', 19:03:44 INFO - 'tscrollx', 19:03:44 INFO - 'cart', 19:03:44 INFO - 'tsvg_static']}, 19:03:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:03:44 INFO - 'talos_options': ['--disable-e10s'], 19:03:44 INFO - 'tests': ['tp5o']}, 19:03:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:03:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:03:44 INFO - 'talos_options': ['--disable-e10s', 19:03:44 INFO - '--xperf_path', 19:03:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:03:44 INFO - 'tests': ['tp5n']}, 19:03:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:03:44 INFO - 'talos_options': ['--xperf_path', 19:03:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:03:44 INFO - 'tests': ['tp5n']}}, 19:03:44 INFO - 'talos.zip': {'path': '', 19:03:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:03:44 INFO - Running post-action listener: _resource_record_post_action 19:03:44 INFO - [mozharness: 2017-06-02 02:03:44.351004Z] Finished populate-webroot step (success) 19:03:44 INFO - [mozharness: 2017-06-02 02:03:44.351156Z] Running create-virtualenv step. 19:03:44 INFO - Running pre-action listener: _resource_record_pre_action 19:03:44 INFO - Running main action method: create_virtualenv 19:03:44 INFO - Creating virtualenv /builds/slave/test/build/venv 19:03:44 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 19:03:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:03:44 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:03:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:03:44 INFO - Using real prefix '/tools/python27' 19:03:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:03:45 INFO - Installing distribute.............................................................................................................................................................................................done. 19:03:48 INFO - Installing pip.................done. 19:03:48 INFO - Return code: 0 19:03:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:03:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:03:48 INFO - Reading from file tmpfile_stdout 19:03:48 INFO - Output received: 19:03:48 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) 19:03:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:03:48 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')]} 19:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:48 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')]} 19:03:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:48 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 0x108f37c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f3b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a0476da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ec0c60>, '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 0x108c55cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c7ebe8>, '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.7MedGtEOkO/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.6gObjV8OxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 19:03:48 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 19:03:48 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 19:03:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MedGtEOkO/Render', 19:03:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:48 INFO - 'HOME': '/Users/cltbld', 19:03:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:48 INFO - 'LOGNAME': 'cltbld', 19:03:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:48 INFO - 'MOZ_NO_REMOTE': '1', 19:03:48 INFO - 'NO_EM_RESTART': '1', 19:03:48 INFO - 'PAGER': '/bin/cat', 19:03:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:48 INFO - 'PWD': '/builds/slave/test', 19:03:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:48 INFO - 'SHELL': '/bin/bash', 19:03:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gObjV8OxF/Listeners', 19:03:48 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 19:03:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:48 INFO - 'USER': 'cltbld', 19:03:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:48 INFO - 'XPC_FLAGS': '0x0', 19:03:48 INFO - 'XPC_SERVICE_NAME': '0', 19:03:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:48 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 19:03:48 INFO - Cleaning up... 19:03:48 INFO - Return code: 0 19:03:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:03:48 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')]} 19:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:48 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')]} 19:03:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:48 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 0x108f37c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f3b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a0476da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ec0c60>, '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 0x108c55cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c7ebe8>, '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.7MedGtEOkO/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.6gObjV8OxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 19:03:48 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 19:03:48 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 19:03:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MedGtEOkO/Render', 19:03:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:48 INFO - 'HOME': '/Users/cltbld', 19:03:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:48 INFO - 'LOGNAME': 'cltbld', 19:03:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:48 INFO - 'MOZ_NO_REMOTE': '1', 19:03:48 INFO - 'NO_EM_RESTART': '1', 19:03:48 INFO - 'PAGER': '/bin/cat', 19:03:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:48 INFO - 'PWD': '/builds/slave/test', 19:03:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:48 INFO - 'SHELL': '/bin/bash', 19:03:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gObjV8OxF/Listeners', 19:03:48 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 19:03:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:48 INFO - 'USER': 'cltbld', 19:03:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:48 INFO - 'XPC_FLAGS': '0x0', 19:03:48 INFO - 'XPC_SERVICE_NAME': '0', 19:03:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:48 INFO - Downloading/unpacking psutil>=3.1.1 19:03:48 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 19:03:48 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 19:03:48 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 19:03:48 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 19:03:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:03:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:03:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:03:50 INFO - Installing collected packages: psutil 19:03:50 INFO - Running setup.py install for psutil 19:03:50 INFO - building 'psutil._psutil_osx' extension 19:03:50 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 19:03:51 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 19:03:51 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 19:03:51 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 19:03:51 INFO - building 'psutil._psutil_posix' extension 19:03:51 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 19:03:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:03:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:03:51 INFO - ^ 19:03:51 INFO - 1 warning generated. 19:03:51 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 19:03:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:03:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:03:51 INFO - Successfully installed psutil 19:03:51 INFO - Cleaning up... 19:03:51 INFO - Return code: 0 19:03:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:03:51 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')]} 19:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:52 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')]} 19:03:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:52 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 0x108f37c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f3b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a0476da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ec0c60>, '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 0x108c55cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c7ebe8>, '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.7MedGtEOkO/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.6gObjV8OxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 19:03:52 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 19:03:52 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 19:03:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MedGtEOkO/Render', 19:03:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:52 INFO - 'HOME': '/Users/cltbld', 19:03:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:52 INFO - 'LOGNAME': 'cltbld', 19:03:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:52 INFO - 'MOZ_NO_REMOTE': '1', 19:03:52 INFO - 'NO_EM_RESTART': '1', 19:03:52 INFO - 'PAGER': '/bin/cat', 19:03:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:52 INFO - 'PWD': '/builds/slave/test', 19:03:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:52 INFO - 'SHELL': '/bin/bash', 19:03:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gObjV8OxF/Listeners', 19:03:52 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 19:03:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:52 INFO - 'USER': 'cltbld', 19:03:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:52 INFO - 'XPC_FLAGS': '0x0', 19:03:52 INFO - 'XPC_SERVICE_NAME': '0', 19:03:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:03:52 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 19:03:52 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 19:03:52 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 19:03:52 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 19:03:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:03:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:03:54 INFO - Installing collected packages: mozsystemmonitor 19:03:54 INFO - Running setup.py install for mozsystemmonitor 19:03:54 INFO - Successfully installed mozsystemmonitor 19:03:54 INFO - Cleaning up... 19:03:54 INFO - Return code: 0 19:03:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:03: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')]} 19:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03: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')]} 19:03:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:54 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 0x108f37c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f3b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a0476da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ec0c60>, '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 0x108c55cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c7ebe8>, '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.7MedGtEOkO/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.6gObjV8OxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 19:03:54 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 19:03:54 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 19:03:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MedGtEOkO/Render', 19:03:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:54 INFO - 'HOME': '/Users/cltbld', 19:03:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:54 INFO - 'LOGNAME': 'cltbld', 19:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:54 INFO - 'MOZ_NO_REMOTE': '1', 19:03:54 INFO - 'NO_EM_RESTART': '1', 19:03:54 INFO - 'PAGER': '/bin/cat', 19:03:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:54 INFO - 'PWD': '/builds/slave/test', 19:03:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:54 INFO - 'SHELL': '/bin/bash', 19:03:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gObjV8OxF/Listeners', 19:03:54 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 19:03:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:54 INFO - 'USER': 'cltbld', 19:03:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:54 INFO - 'XPC_FLAGS': '0x0', 19:03:54 INFO - 'XPC_SERVICE_NAME': '0', 19:03:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:55 INFO - Downloading/unpacking jsonschema==2.5.1 19:03:55 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 19:03:55 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 19:03:55 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 19:03:55 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 19:03:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:03:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:56 INFO - Downloading functools32-3.2.3-2.tar.gz 19:03:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:03:56 INFO - warning: no files found matching '*.txt' 19:03:56 INFO - no previously-included directories found matching 'build' 19:03:56 INFO - no previously-included directories found matching 'dist' 19:03:56 INFO - no previously-included directories found matching '.git*' 19:03:56 INFO - Installing collected packages: jsonschema, functools32 19:03:56 INFO - Running setup.py install for functools32 19:03:57 INFO - warning: no files found matching '*.txt' 19:03:57 INFO - no previously-included directories found matching 'build' 19:03:57 INFO - no previously-included directories found matching 'dist' 19:03:57 INFO - no previously-included directories found matching '.git*' 19:03:57 INFO - Successfully installed jsonschema functools32 19:03:57 INFO - Cleaning up... 19:03:57 INFO - Return code: 0 19:03:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:03:57 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')]} 19:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:57 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')]} 19:03:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:57 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 0x108f37c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f3b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a0476da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ec0c60>, '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 0x108c55cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c7ebe8>, '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.7MedGtEOkO/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.6gObjV8OxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 19:03:57 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 19:03:57 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 19:03:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MedGtEOkO/Render', 19:03:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:57 INFO - 'HOME': '/Users/cltbld', 19:03:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:57 INFO - 'LOGNAME': 'cltbld', 19:03:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:57 INFO - 'MOZ_NO_REMOTE': '1', 19:03:57 INFO - 'NO_EM_RESTART': '1', 19:03:57 INFO - 'PAGER': '/bin/cat', 19:03:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:57 INFO - 'PWD': '/builds/slave/test', 19:03:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:57 INFO - 'SHELL': '/bin/bash', 19:03:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gObjV8OxF/Listeners', 19:03:57 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 19:03:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:57 INFO - 'USER': 'cltbld', 19:03:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:57 INFO - 'XPC_FLAGS': '0x0', 19:03:57 INFO - 'XPC_SERVICE_NAME': '0', 19:03:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:03:57 INFO - Cleaning up... 19:03:57 INFO - Return code: 0 19:03:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:03:57 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')]} 19:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:57 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')]} 19:03:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:57 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 0x108f37c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f3b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a0476da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ec0c60>, '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 0x108c55cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c7ebe8>, '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.7MedGtEOkO/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.6gObjV8OxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 19:03:57 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 19:03:57 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 19:03:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MedGtEOkO/Render', 19:03:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:57 INFO - 'HOME': '/Users/cltbld', 19:03:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:57 INFO - 'LOGNAME': 'cltbld', 19:03:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:57 INFO - 'MOZ_NO_REMOTE': '1', 19:03:57 INFO - 'NO_EM_RESTART': '1', 19:03:57 INFO - 'PAGER': '/bin/cat', 19:03:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:57 INFO - 'PWD': '/builds/slave/test', 19:03:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:57 INFO - 'SHELL': '/bin/bash', 19:03:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gObjV8OxF/Listeners', 19:03:57 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 19:03:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:57 INFO - 'USER': 'cltbld', 19:03:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:57 INFO - 'XPC_FLAGS': '0x0', 19:03:57 INFO - 'XPC_SERVICE_NAME': '0', 19:03:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:57 INFO - Downloading/unpacking blobuploader==1.2.4 19:03:57 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 19:03:57 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 19:03:57 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 19:03:57 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 19:03:58 INFO - Downloading blobuploader-1.2.4.tar.gz 19:03:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:03:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:03:59 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 19:03:59 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 19:03:59 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 19:03:59 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 19:03:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:03:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:03:59 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 19:03:59 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 19:03:59 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 19:03:59 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 19:03:59 INFO - Downloading docopt-0.6.1.tar.gz 19:03:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:03:59 INFO - Installing collected packages: blobuploader, requests, docopt 19:03:59 INFO - Running setup.py install for blobuploader 19:04:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:04:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:04:00 INFO - Running setup.py install for requests 19:04:00 INFO - Running setup.py install for docopt 19:04:00 INFO - Successfully installed blobuploader requests docopt 19:04:00 INFO - Cleaning up... 19:04:00 INFO - Return code: 0 19:04:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:04: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')]} 19:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04: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')]} 19:04:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:00 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 0x108f37c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f3b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a0476da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ec0c60>, '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 0x108c55cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c7ebe8>, '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.7MedGtEOkO/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.6gObjV8OxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 19:04:00 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 19:04:00 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 19:04:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MedGtEOkO/Render', 19:04:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:00 INFO - 'HOME': '/Users/cltbld', 19:04:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:00 INFO - 'LOGNAME': 'cltbld', 19:04:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:00 INFO - 'MOZ_NO_REMOTE': '1', 19:04:00 INFO - 'NO_EM_RESTART': '1', 19:04:00 INFO - 'PAGER': '/bin/cat', 19:04:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:00 INFO - 'PWD': '/builds/slave/test', 19:04:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:00 INFO - 'SHELL': '/bin/bash', 19:04:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gObjV8OxF/Listeners', 19:04:00 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 19:04:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:00 INFO - 'USER': 'cltbld', 19:04:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:00 INFO - 'XPC_FLAGS': '0x0', 19:04:00 INFO - 'XPC_SERVICE_NAME': '0', 19:04:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:04:01 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-SjZTNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:04:01 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-1T3YaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:04:01 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-GURwEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:04:01 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-E5Zjri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:04:01 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-S4VLhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:04:01 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-OUJYUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:04:01 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-iL5SgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:04:01 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-9qUYy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:04:01 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-C9AQM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:04:02 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-cD0Fhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:04:02 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-Fi10uc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:04:02 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-5pEuRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:04:02 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-o_6TtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:04:02 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-89ikZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:02 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-32RHqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:04:02 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-thN6E4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:04:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:04:02 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-OzC0lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:04:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:04:02 INFO - Running setup.py install for manifestparser 19:04:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:04:03 INFO - Running setup.py install for mozcrash 19:04:03 INFO - Running setup.py install for mozdebug 19:04:03 INFO - Running setup.py install for mozdevice 19:04:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:04:03 INFO - Running setup.py install for mozfile 19:04:03 INFO - Running setup.py install for mozhttpd 19:04:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:04:03 INFO - Running setup.py install for mozinfo 19:04:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:04:03 INFO - Running setup.py install for mozInstall 19:04:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:04:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:04:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:04:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:04:03 INFO - Running setup.py install for mozleak 19:04:04 INFO - Running setup.py install for mozlog 19:04:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:04:04 INFO - Running setup.py install for moznetwork 19:04:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:04:04 INFO - Running setup.py install for mozprocess 19:04:04 INFO - Running setup.py install for mozprofile 19:04:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:04:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:04:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:04:04 INFO - Running setup.py install for mozrunner 19:04:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:04:04 INFO - Running setup.py install for mozscreenshot 19:04:05 INFO - Running setup.py install for moztest 19:04:05 INFO - Running setup.py install for mozversion 19:04:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:04:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:04:05 INFO - Cleaning up... 19:04:05 INFO - Return code: 0 19:04:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:04:05 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')]} 19:04:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:05 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')]} 19:04:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:05 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 0x108f37c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f3b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a0476da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ec0c60>, '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 0x108c55cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c7ebe8>, '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.7MedGtEOkO/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.6gObjV8OxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 19:04:05 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 19:04:05 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 19:04:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MedGtEOkO/Render', 19:04:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:05 INFO - 'HOME': '/Users/cltbld', 19:04:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:05 INFO - 'LOGNAME': 'cltbld', 19:04:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:05 INFO - 'MOZ_NO_REMOTE': '1', 19:04:05 INFO - 'NO_EM_RESTART': '1', 19:04:05 INFO - 'PAGER': '/bin/cat', 19:04:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:05 INFO - 'PWD': '/builds/slave/test', 19:04:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:05 INFO - 'SHELL': '/bin/bash', 19:04:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gObjV8OxF/Listeners', 19:04:05 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 19:04:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:05 INFO - 'USER': 'cltbld', 19:04:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:05 INFO - 'XPC_FLAGS': '0x0', 19:04:05 INFO - 'XPC_SERVICE_NAME': '0', 19:04:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:04:05 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-m62JRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:04:05 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)) 19:04:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:04:05 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-dqstbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:04:05 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)) 19:04:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:04:05 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-stljUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:04:05 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)) 19:04:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:04:06 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-b17GMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:04:06 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-PKMeGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:04:06 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-PfXlzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:04:06 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-_Lez6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:04:06 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-9nwN8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:04:06 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-cEfUAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:04:06 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-tTBncp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:04:06 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-E9OpdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:04:06 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-y1u_UZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:04:06 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)) 19:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:04:06 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-20bJt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:04:07 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)) 19:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:04:07 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-8yR3P1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:04:07 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)) 19:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:07 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-De6TjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:07 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)) 19:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:04:07 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-wivgRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:04:07 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)) 19:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:04:07 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-flaYRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:04:07 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)) 19:04:07 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)) 19:04:07 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)) 19:04:07 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)) 19:04:07 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:04:07 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:04:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:04:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:04:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:04:08 INFO - Downloading blessings-1.6.tar.gz 19:04:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:04:08 INFO - Installing collected packages: blessings 19:04:08 INFO - Running setup.py install for blessings 19:04:09 INFO - Successfully installed blessings 19:04:09 INFO - Cleaning up... 19:04:09 INFO - Return code: 0 19:04:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:04:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:04:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:04:09 INFO - Reading from file tmpfile_stdout 19:04:09 INFO - Current package versions: 19:04:09 INFO - blessings == 1.6 19:04:09 INFO - blobuploader == 1.2.4 19:04:09 INFO - docopt == 0.6.1 19:04:09 INFO - functools32 == 3.2.3-2 19:04:09 INFO - jsonschema == 2.5.1 19:04:09 INFO - manifestparser == 1.1 19:04:09 INFO - mozInstall == 1.12 19:04:09 INFO - mozcrash == 1.0 19:04:09 INFO - mozdebug == 0.1 19:04:09 INFO - mozdevice == 0.51 19:04:09 INFO - mozfile == 1.2 19:04:09 INFO - mozhttpd == 0.7 19:04:09 INFO - mozinfo == 0.9 19:04:09 INFO - mozleak == 0.1 19:04:09 INFO - mozlog == 3.4 19:04:09 INFO - moznetwork == 0.27 19:04:09 INFO - mozprocess == 0.25 19:04:09 INFO - mozprofile == 0.28 19:04:09 INFO - mozrunner == 6.13 19:04:09 INFO - mozscreenshot == 0.1 19:04:09 INFO - mozsystemmonitor == 0.3 19:04:09 INFO - moztest == 0.8 19:04:09 INFO - mozversion == 1.4 19:04:09 INFO - psutil == 3.1.1 19:04:09 INFO - requests == 1.2.3 19:04:09 INFO - wsgiref == 0.1.2 19:04:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:04: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')]} 19:04:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04: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')]} 19:04:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:09 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 0x108f37c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f3b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a0476da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ec0c60>, '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 0x108c55cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c7ebe8>, '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.7MedGtEOkO/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.6gObjV8OxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 19:04:09 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 19:04:09 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 19:04:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MedGtEOkO/Render', 19:04:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:09 INFO - 'HOME': '/Users/cltbld', 19:04:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:09 INFO - 'LOGNAME': 'cltbld', 19:04:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:09 INFO - 'MOZ_NO_REMOTE': '1', 19:04:09 INFO - 'NO_EM_RESTART': '1', 19:04:09 INFO - 'PAGER': '/bin/cat', 19:04:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:09 INFO - 'PWD': '/builds/slave/test', 19:04:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:09 INFO - 'SHELL': '/bin/bash', 19:04:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gObjV8OxF/Listeners', 19:04:09 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 19:04:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:09 INFO - 'USER': 'cltbld', 19:04:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:09 INFO - 'XPC_FLAGS': '0x0', 19:04:09 INFO - 'XPC_SERVICE_NAME': '0', 19:04:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:09 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)) 19:04:09 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)) 19:04:09 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)) 19:04:09 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)) 19:04:09 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)) 19:04:09 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)) 19:04:09 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)) 19:04:09 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)) 19:04:09 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)) 19:04:09 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:04: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 19:04: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 19:04: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 19:04: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 19:04:11 INFO - Downloading marionette_driver-1.4.0.tar.gz 19:04:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 19:04:11 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)) 19:04:11 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)) 19:04:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:04:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:04:11 INFO - Installing collected packages: marionette-driver 19:04:11 INFO - Running setup.py install for marionette-driver 19:04:11 INFO - Successfully installed marionette-driver 19:04:11 INFO - Cleaning up... 19:04:11 INFO - Return code: 0 19:04:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:04:11 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')]} 19:04:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:11 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')]} 19:04:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:11 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 0x108f37c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f3b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a0476da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ec0c60>, '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 0x108c55cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c7ebe8>, '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.7MedGtEOkO/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.6gObjV8OxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 19:04:11 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 19:04:11 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 19:04:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MedGtEOkO/Render', 19:04:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:11 INFO - 'HOME': '/Users/cltbld', 19:04:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:11 INFO - 'LOGNAME': 'cltbld', 19:04:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:11 INFO - 'MOZ_NO_REMOTE': '1', 19:04:11 INFO - 'NO_EM_RESTART': '1', 19:04:11 INFO - 'PAGER': '/bin/cat', 19:04:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:11 INFO - 'PWD': '/builds/slave/test', 19:04:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:11 INFO - 'SHELL': '/bin/bash', 19:04:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gObjV8OxF/Listeners', 19:04:11 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 19:04:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:11 INFO - 'USER': 'cltbld', 19:04:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:11 INFO - 'XPC_FLAGS': '0x0', 19:04:11 INFO - 'XPC_SERVICE_NAME': '0', 19:04:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:04:11 INFO - Cleaning up... 19:04:11 INFO - Return code: 0 19:04:11 INFO - Running post-action listener: _resource_record_post_action 19:04:11 INFO - Running post-action listener: _start_resource_monitoring 19:04:11 INFO - Starting resource monitoring. 19:04:11 INFO - [mozharness: 2017-06-02 02:04:11.725266Z] Finished create-virtualenv step (success) 19:04:11 INFO - [mozharness: 2017-06-02 02:04:11.726102Z] Running install step. 19:04:11 INFO - Running pre-action listener: _resource_record_pre_action 19:04:11 INFO - Running main action method: install 19:04:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:04:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:04:11 INFO - Reading from file tmpfile_stdout 19:04:11 INFO - Detecting whether we're running mozinstall >=1.0... 19:04:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:04:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:04:12 INFO - Reading from file tmpfile_stdout 19:04:12 INFO - Output received: 19:04:12 INFO - Usage: mozinstall [options] installer 19:04:12 INFO - Options: 19:04:12 INFO - -h, --help show this help message and exit 19:04:12 INFO - -d DEST, --destination=DEST 19:04:12 INFO - Directory to install application into. [default: 19:04:12 INFO - "/builds/slave/test"] 19:04:12 INFO - --app=APP Application being installed. [default: firefox] 19:04:12 INFO - mkdir: /builds/slave/test/build/application 19:04:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:04:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:04:33 INFO - Reading from file tmpfile_stdout 19:04:33 INFO - Output received: 19:04:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:04:33 INFO - Running post-action listener: _resource_record_post_action 19:04:33 INFO - [mozharness: 2017-06-02 02:04:33.527217Z] Finished install step (success) 19:04:33 INFO - [mozharness: 2017-06-02 02:04:33.527362Z] Running setup-mitmproxy step. 19:04:33 INFO - Running pre-action listener: _resource_record_pre_action 19:04:33 INFO - Running main action method: setup_mitmproxy 19:04:33 INFO - Skipping: mitmproxy is not required 19:04:33 INFO - Running post-action listener: _resource_record_post_action 19:04:33 INFO - [mozharness: 2017-06-02 02:04:33.528011Z] Finished setup-mitmproxy step (success) 19:04:33 INFO - [mozharness: 2017-06-02 02:04:33.528129Z] Running run-tests step. 19:04:33 INFO - Running pre-action listener: _resource_record_pre_action 19:04:33 INFO - Running main action method: run_tests 19:04:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:04:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:04:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:04:33 INFO - 2017-06-01 19:04:33.542 screenresolution[1624:7438] starting screenresolution argv=screenresolution get 19:04:33 INFO - 2017-06-01 19:04:33.545 screenresolution[1624:7438] Display 0: 1600x1200x32@60 19:04:33 INFO - 2017-06-01 19:04:33.551 screenresolution[1625:7440] starting screenresolution argv=screenresolution list 19:04:33 INFO - Available Modes on Display 0 19:04:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:04:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:04:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:04:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:04:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:04:33 INFO - Intel Iris: 19:04:33 INFO - Chipset Model: Intel Iris 19:04:33 INFO - Type: GPU 19:04:33 INFO - Bus: Built-In 19:04:33 INFO - VRAM (Dynamic, Max): 1536 MB 19:04:33 INFO - Vendor: Intel (0x8086) 19:04:33 INFO - Device ID: 0x0a2e 19:04:33 INFO - Revision ID: 0x0009 19:04:33 INFO - Displays: 19:04:33 INFO - Display: 19:04:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:04:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:04:33 INFO - Display Serial Number: 1600x1200 60 19:04:33 INFO - Main Display: Yes 19:04:33 INFO - Mirror: Off 19:04:33 INFO - Online: Yes 19:04:33 INFO - Rotation: Supported 19:04:33 INFO - Return code: 0 19:04:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:04:33 INFO - Python 2.7.3 19:04:33 INFO - Return code: 0 19:04:33 INFO - grabbing minidump binary from tooltool 19:04:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:33 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 0x108ec0c60>, '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 0x108c55cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c7ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:04:33 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 19:04:33 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 19:04:33 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 19:04:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:04:33 INFO - Return code: 0 19:04:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:04:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:04:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:04:33 INFO - ENV: RUST_BACKTRACE is now 1 19:04:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:04:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:04:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0289', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0289 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:04:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7MedGtEOkO/Render', 19:04:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:33 INFO - 'HOME': '/Users/cltbld', 19:04:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:33 INFO - 'LOGNAME': 'cltbld', 19:04:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:04:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:04:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:33 INFO - 'MOZ_NO_REMOTE': '1', 19:04:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:04:33 INFO - 'NO_EM_RESTART': '1', 19:04:33 INFO - 'PAGER': '/bin/cat', 19:04:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:33 INFO - 'PWD': '/builds/slave/test', 19:04:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:04:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:33 INFO - 'RUST_BACKTRACE': '1', 19:04:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:04:33 INFO - 'SHELL': '/bin/bash', 19:04:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6gObjV8OxF/Listeners', 19:04:33 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 19:04:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:33 INFO - 'USER': 'cltbld', 19:04:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:33 INFO - 'XPC_FLAGS': '0x0', 19:04:33 INFO - 'XPC_SERVICE_NAME': '0', 19:04:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0289', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:04:34 INFO - mozversion application_buildid: 20170601173021 19:04:34 INFO - mozversion application_changeset: 1e229cf8933b08191e4243a6b9dbb3c821db60ab 19:04:34 INFO - mozversion application_display_name: Nightly 19:04:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:04:34 INFO - mozversion application_name: Firefox 19:04:34 INFO - mozversion application_remotingname: firefox 19:04:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:04:34 INFO - mozversion application_vendor: Mozilla 19:04:34 INFO - mozversion application_version: 55.0a1 19:04:34 INFO - mozversion platform_buildid: 20170601173021 19:04:34 INFO - mozversion platform_changeset: 1e229cf8933b08191e4243a6b9dbb3c821db60ab 19:04:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:04:34 INFO - mozversion platform_version: 55.0a1 19:04:34 INFO - using testdate: 1496369074 19:04:34 INFO - actual date: 1496369074 19:04:34 INFO - starting webserver on 'localhost:49229' 19:04:34 INFO - SUITE-START | Running 2 tests 19:04:34 INFO - TEST-START | dromaeo_css 19:04:34 INFO - operating with platform_type : mac_ 19:04:34 INFO - Initialising browser for dromaeo_css test... 19:04:34 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpw_cwzK/profile 19:04:34 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 19:05:08 INFO - TEST-INFO | 1635: killed by SIGTERM 19:05:08 INFO - Browser initialized. 19:05:08 INFO - Running cycle 1/1 for dromaeo_css test... 19:05:08 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpw_cwzK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:05:10 INFO - PID 1646 | 2017-06-01 19:05:10.351 plugin-container[1647:8012] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3f, name = 'com.apple.tsm.portname' 19:05:10 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:05:10 INFO - PID 1646 | 2017-06-01 19:05:10.351 plugin-container[1647:8012] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00f, name = 'com.apple.CFPasteboardClient' 19:05:10 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:05:10 INFO - PID 1646 | 2017-06-01 19:05:10.351 plugin-container[1647:8012] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:05:11 INFO - PID 1646 | 2017-06-01 19:05:11.078 plugin-container[1648:8060] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 19:05:11 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:05:11 INFO - PID 1646 | 2017-06-01 19:05:11.078 plugin-container[1648:8060] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480b, name = 'com.apple.CFPasteboardClient' 19:05:11 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:05:11 INFO - PID 1646 | 2017-06-01 19:05:11.078 plugin-container[1648:8060] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:05:20 INFO - PID 1646 | RSS: Main: 260378624 19:05:20 INFO - PID 1646 | 19:08:22 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 19:08:23 INFO - PID 1646 | RSS: Main: 271691776 19:08:23 INFO - PID 1646 | 19:11:25 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 19:11:26 INFO - PID 1646 | RSS: Main: 284106752 19:11:26 INFO - PID 1646 | 19:14:28 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 19:14:28 INFO - PID 1646 | RSS: Main: 287952896 19:14:28 INFO - PID 1646 | 19:17:30 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 19:17:31 INFO - PID 1646 | RSS: Main: 289509376 19:17:31 INFO - PID 1646 | 19:20:34 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 19:20:34 INFO - PID 1646 | RSS: Main: 298016768 19:20:34 INFO - PID 1646 | 19:23:36 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 19:23:36 INFO - PID 1646 | RSS: Main: 297439232 19:23:36 INFO - PID 1646 | 19:23:36 INFO - PID 1646 | __start_tp_report 19:23:36 INFO - PID 1646 | _x_x_mozilla_page_load 19:23:36 INFO - PID 1646 | _x_x_mozilla_page_load_details 19:23:36 INFO - PID 1646 | |i|pagename|runs| 19:23:36 INFO - PID 1646 | |0;dojo.html;3519.577650681918;3582.5700915890097;3600.315939971407;3613.945790813138;3628.727845411595;4861.586765124968;5522.784987302792;6082.482988945945;6169.074638804182;6186.072089186624;6099.908501372472;6125.937554508985;6138.173828368832;6162;6217.253929528463;4996.850094497171;5261.404930330137;5345.652532585389;5388.110961691316;5390.6226564140625;5076.68006318358;5528.198411230369;5541.806036788713;5548.112302031675;5572.749226284806;5339.279197308352;5400.351957765054;5401.297831281908;5451.863703407446;5456.890862182754;5451.964126815892;5455.509004189602;5456.836294911159;5468.043092458804;5487.341519017744;5367.651102678314;5397.46025397461;5404.729763511808;5415.268938693265;5431.348238211401;6069.544784141163;6094.573379863411;6201.658908760016;6211.130441738161;6227.159333489966;6385.7764978226005;6554.147960765118;6637.73449062037;6663.800085997428;6688.4649228061635;6602.910519764233;6732.259451460335;6741.528093033489;6779.32206779323;6799.218089919673;6179.752809887644;6267.091271765568;6269.059641053787;6283.6858157091965;6298.307186209513;5624.887502249932;6802.93197068023;6877.140357455318;6904.033435319059;6908.0674108995645;5439.728013599304;6293.402126798039;6332.461740751995;6538.705758240891;6598.769043083565;6702.798916032527;6718.49611279156;6729.427998620172;6740.662966851638;6762.289959554274;6598.43913267368;6622.86754264912;6624.86750264992;6624.867502650017;6640.501962352843;6406.67966601668;6419.550631455847;6455.4835613150835;6464.450521705708;6587.538872278895;5287.603429742785;5315.893682126413;5320.680759154463;5322.733863306897;5377.650452720639;4207.600277973587;4451.0430257494145;4725.10223057618;4754.857354279377;4781.378420805273;6123.8162855114415;6131.590761710035;6259.78090766821;6268.153799237136;6292.559520833589;5925.762969481172;6259.4679452246155;6672.866542669217;6754.662266886636;6778.322167783281;6025.186599809057;6272.8118156455375;6295.779647712308;6299.464545513591;6305.8108256752375;5747.4252574742195;5934.7922822701;5947.881042379128;5950.285965684146;6004;6033.517318614413;6740.224874139439;6802.693878775467;6874.484413668894;6877.518573699793;4970.925436118533;6639.933600664125;6715.395614394728;6807.21717002551;6823.249442561413;6887.276835932155;6909.481788865955;6910.7581234656545;6923.6805670101085;6927.44580433554;5376.892462150813;6423.197100362455;6489.902651460137;6545.312742162195;6587.604743715392;6748.763793267212;6753.966230169014;6758.4931130166415;6759.5606285591275;6763.458923286224;6300.527460440394;6384.904226436514;6544.738210471528;6550;6553.0170474429215;6420.390062944013;6454.419102280817;6481.902771458526;6514.609123452608;6517.055027021151;6312.652804095819;6375.151206150164;6375.234971803413;6386.840328991812;6400.647964362005;6282.371762823681;6494.545381823227;6502.967485162733;6517.804465866032;6543.1821022372205;6247.250329960434;6281.560290779602;6298.559100862895;6299.590526615939;6349.333320001518 19:23:36 INFO - PID 1646 | |1;ext.html;778.6885245901639;809.8582748019097;836.6987884361629;1230.8646048934622;1301.5314486784762;36975.81512092439;38210.99999999996;38211.808940955256;38234.426483602765;38322;37462.876113716564;38587.22825543487;38629.61370386295;38671.613283867155;38706.806465967704;6544.3782840630065;8031.076426210973;8103.95948020262;8327.125651806564;8336.541490218066;7009.929900701004;7097.716091356339;8311.709090181814;8334.541600212016;8336.833263334729;8266.338692904554;8304.584770761438;8314.251717345438;8341.165883411672;8357.456765310266;8226.506409615442;8307.83384332313;8343.624536895853;8349.8330033399;8359.874601880976;8140.389470789655;8174.877376839352;8185.754427367188;8190.467619604707;8207.179282071746;35842;36050.81974590137;36055.81972090149;36131.63868361309;36351.27297454036;3520.119970007498;3562.5546806649168;3563.2339047104997;3563.8396272167815;3564;29996.250093747614;30023.249418764488;30062.54906176409;30067.548986765218;30083.247918801986;3009.277773334406;3042.878284868603;3045.649750278702;3065.73941214997;3077.5999120114243;210311.94844025836;211523;257143.99999999814;279368;280141;42543.7236882894;44168;46473;46506.76746616313;46561;48168;48278.75860620745;48292.758536207795;49143;49858.75070624624;13910.721785564434;13982.650433739238;14021.85978140226;14153;14195.290235488184;11192.440377981231;11235;11313.943430282796;11327.60353387644;11351.60269390584;13722.176669399669;13763.105398149288;13795.655108622364;13804.861951380559;13879.306034698224;6174.475169610543;6214;6236.220472440945;6237.781677641261;6262.843428914222;987.9209663226925;1007.808516381885;1024.3802499487851;1033.6588925654517;1069.7593041565585;23881.283561493183;23917.880410597834;24144.87927560386;24160;24308.14921477775;989.1690979577189;994.3636072913341;1067.990748742431;1126.6507382711386;1151.758130792543;12240.693982650506;12429.627111186679;12473.064520160842;12488.375581220902;12502.3123728194;341180;344439;345601;345675;345793.2710336432;52604.736976314874;52718.73640631849;52952.94094118173;52981;53005.73497132567;1477.1875468492287;1517.286875168647;1544.4594391962723;1547.1104115133844;1549.8140223173066;22565.323040308816;23292.951817168607;23715.52568948577;23798.405039874142;23969.16107936214;36511.45232821457;36845;36850.89447316585;36935.076623083565;36965.815170923976;281761;285012;285522.14477854955;286346.70479943237;286440;33708.325833482704;34743.9576812696;34971.30057398888;35259.64740352564;35314.11714707153;42870;44227.11545769131;44644;44688.10623787441;44760;28499.145025649264;28621.71378286274;29500.627151930883;30022.69977300199;31010;27836.74734636906;30074.849625751733;30662.08013759591;30789;31231;36072.06454372747;36411.635883640825;36475.89337492806;36874.07814804544;37336.81331593324;30124.84937575298;31128.489624167458;31198.844005779825;31290.380473797813;31403;22085;23843.165489207793;23969;24432.877835610707;24465.143719969714 19:23:36 INFO - PID 1646 | |2;jquery.html;400.27550134256995;406.808799864064;419.771224682548;433.1920967395807;455.510326399121;14220.288985550733;15061.472848450307;21129.788702112975;21917.452063698394;21933.232336868212;21820.127194912224;22692.319230423112;22962.770372296272;23033.963471643776;23035.76964230357;5927.673977931212;6080.574359794816;6122.0816877468515;6122.47958923492;6125.540584456184;3535.9116022099524;3711.7401781875146;5976.581639285252;6015.308239552442;6109.205803245572;5816.767329306823;6115.816525504242;6122.877542449149;6126.7855625053135;6146.170267013963;6066.484348830355;6074.42292982166;6097.475631092249;6143.87712245757;6148.38516148386;5364.8122315718765;5393.514583687448;5410.431904649994;5416.0792665247;5418.891622167574;27177.118635595652;27818.304542386602;28048;28056.438871222665;28121;2208.3595757230382;2253.9436514087115;2270.579942710604;2274.1926616051396;2276.112316196685;17806.55483612907;18062.36781712647;18192.181351839197;18526.907365463223;18655.973921434317;1953.9316123935737;2120.2155202575145;2193.385851961453;2204.3166618348364;2212.192549719357;175307.12346438188;176862;196090;198464;199765;10414.323069000491;10611.681649550526;10748;11334.659960201207;12873.613791586267;15921.363145474283;15941.521754347388;16050.438234661731;16058.75911861323;16098.195090245441;8825.867611985825;8899.199072083544;9017.864732029026;9035.412698174598;9085.909140908638;8704.564771761387;8756.95621521901;8767.430117042371;8773.561321934005;8794.558747832303;6837.487188460886;7406.259374062551;7816.8827467588035;7932.524048557105;8064.032316122103;2240.05917514641;2251.90992360307;2254.8872556372116;2263.1286954522297;2433.051110067075;928.6981730937472;930.3115694386106;975.8243516167158;981.788915383196;1019.8368261078192;14805.741511971388;16626;19897.303594374127;19959;20428.59142817165;849.4478588917129;993.3493561717087;1004.9602534247549;1017.9491025448846;1023.1098943918689;9753.87830399499;9933.602655893683;10491.527881245362;10629.043386095289;10726.946365268124;556284;561634;564078;569429;569774;111483;144684;144911.27544362211;146842;147490;1183.479269121601;1183.7573297473998;1210.3222195570509;1211.9454624541918;1216.1183142221819;15778.211089445711;17843;17847.553811154827;18179;18210.817891820912;46292.53707463017;59745;61623.383766161765;61989.69005155126;62240.688796555725;83011.7548236789;83171;83642;83690;84119;7768.262015108556;7988.241117093867;9771.267154963263;9832.311738178545;10056.089343024802;16970.406035789183;17550;17762;17998.280068797103;18095.276188952295;14337.78493322622;14572.489962851248;14710.0438471499;14797.556073317817;14804.407823687363;12798.744025119258;13399.866001339862;13589.796153057514;13754;13808.240546770025;15038;15097.698046039237;15231.314590843216;15251.771223431435;15329.310181041657;9983.752030996126;10087.193024557871;10151.08640222354;10176;10218.131458826083;14552.927235363755;14703.55889323322;14725.263736812902;14843.92578037103;14904.552863414116 19:23:36 INFO - PID 1646 | |3;mootools.html;1357.8710022547857;1424.5512663510992;1427.1437137717378;1430.4349781836174;1440.7766796146598;9485.76685030946;11460.942695286512;12188.939055304732;12363.876361236375;12399.070069744772;12098.879011209885;12195.75608487832;12316.014718822495;12326.5069397224;12333.814992775093;2538.6699729035245;2581.8321809082445;2596.117320111162;2600.700919394275;2604.895804167831;2498.5502609530276;2514.924552263426;2526.027479420427;2530.7595778401023;2542.364408897776;2505.7368976257503;2516.7609077137645;2527.545041892459;2527.8609676467786;2529.253870108313;2190.8247340212743;2388.6623490845095;2476.826622136442;2489.0914816092177;2493.1897133431708;2000.4998750312423;2476.8018558515278;2481.491294284667;2538.035546492321;2542.733013033623;11775.52897884092;11812.881871181264;11879;11891.345975971319;11899.643010709693;6439.227292724856;6595.6372399518;6702.932970670279;6706.698198581027;6707.731690732365;3888.8833334999995;3958.5843486433796;4155.750654960712;4184.832606695729;4234.978825105885;1188.6671731915078;1214.878512148787;1230.919990200643;1234.481517762542;1250.8498980122351;15743.606409839731;16116.435924742695;16662;16725.832741672548;16849.83150168495;4877.878053048702;5669.914951275734;5819.010768169422;5920.703964801829;5944.227250457413;12368.072394570443;12572.497100116076;12582.87417125817;12584.244945303311;12590.24458532491;6316.557840951181;6390.616563006234;6495.090687303781;6522.967385163044;6523.934760652428;5958.285005799332;6008.309044459856;6075.33171351151;6141.324454310023;6151.446369826737;4376.993291542963;4475.619572336323;4578.40480737502;4579.610733087725;4614.7770840727235;1275.1966261255536;1285.2995117660898;1285.717142228708;1299.7660421124099;1301.0437328563498;752.9360004399578;759.2255898983007;777.8327659553223;790.1387487638501;793.7023616143946;6643.734250630017;6958.165020197507;7078.68145933424;7169.8811017206635;7266.7093316266755;852.1308265569209;905.6784841381346;917.8898532176181;941.3128416256171;963.5086106085946;3587.982060089683;3914.9804250978564;4354.738715677069;4402.185595664812;4496.4154659894;12063.879361206276;15231.543053708407;15250.923745381424;15401;15490.380384784708;14557.708845823025;15197.772033419507;15215.239238038053;15216.619584510254;15339.156346401054;8516.361272904556;8629.61166747498;8689.782755431164;8713;8733.475991440533;5699.430056994267;6855.520113592001;7113.253108423541;7164.928350716426;7176.354128128493;12960;13069.281189534668;13261.403236854558;13311.600651980456;13755.862441375459;14006.719865602427;14302.499412520512;14334.713305734036;14393.424263029361;14404.351804169048;9233.261339092991;9550.90449095528;9616.08647178517;9813.165880900204;9854.605815767576;11089.722756931142;11156;11302.378369189773;11319.490622922152;11333.26333788301;7643.617819109134;9291.024442433447;9372.015938326651;9380;9483.715488535356;9012.81974360496;9097.317701172306;9132.132447417485;9238.9538052312;9390.060993900555;7920.960395197987;8038.83922321562;8046.83906321882;8138.715144969898;8143.83712325762;5836.095405212199;5885.793997210077;5948.524118070631;5991.191189189578;6034.185384972972;5964.105384192406;5983.072623743326;6034.7284372202475;6268.093517279809;6333.1766870306565 19:23:36 INFO - PID 1646 | |4;prototype.html;360.71863946122;394.61130786175613;395.9307121253781;399.7281848343126;403.3296997324348;5945.167676525284;6296.338884417139;6303.180586523757;6354.904676429857;6375.847307348407;5066.3920329560415;6125.540584456173;6131;6152.723127459264;6163.753449862;3683.8158092095423;4486.170058539173;4530.569595888385;4549.658775591827;4549.7725113744345;4232.38630398592;4522.47991480979;4535.478419981695;4559.635229181657;4581.427321584802;4483.327500874862;4498.775061246941;4512.932306015412;4522;4530.614897733697;4137.358709400048;4242.851500197494;4501.392312037899;4516.4806047304655;4517.186906356855;3459.4810778383194;3900.6099390060767;3905;3920.5491368492703;3927.960720392788;5978.192943952554;6006.12911127884;6010.158577799111;6011.729472173719;6036.879262414771;4744.501827308118;4909.69892978361;5406;5407.648502847303;5432.375276843135;5233.921491177635;5709;6599.868002639921;6601.834954126137;6635.5023373247195;4446.177457170402;4452.933206001912;4464.575865292792;4473.865784026484;4495.640348772123;5015.699058056492;6434.906065968749;6737.978606418104;6918.993491887347;7092.787216383516;3799.7910114943943;4426.44669416323;4521.412216411845;4549.431321084864;4560.794764235618;3891.474650922146;4604.470485894098;4621.537846215351;4628.421447319085;4648.790804413809;4481.529439408815;5418.945810541844;5436;5437.673739575638;5443.836684899459;5902.2327097477055;6300.527460440394;6666.033425153326;6679.298673639198;6691.297413771581;1325.821014163095;1567.2790516362602;1580.3125640346257;1586.9682606347808;1594.3702237616076;776.4370831147486;1041.5833666533297;1044.5351818440872;1050.0864248104199;1055;5281.339832520935;6294.842628934313;6316.936830631635;6332;6344;6894.345037221456;6940.479464040217;6945.652717364112;6956.826079347956;6965.024896514492;5509.614326997152;6262.432402714865;6262.749490020439;6270.059491076375;6305.369463053658;3140.811551306929;3471.3057388522398;3586.4261718124976;3615.4215325547784;3621.764585301947;6170.562627762009;7126.928730712627;7135.464840136907;7159.92840071603;7189.921511773171;5889.057750759858;6207.844803879939;7115.576884623096;7140.107486564179;7144.1784194818265;4969.676970996874;7028.402585780266;7105.502614817016;7140.607266600283;7177.4616903731385;6305.842353941188;6807.251202367835;6878.828029299308;6916.51584389108;6950.805915877043;5738.5696072795545;6815.761448349483;6820.283870193558;6827.395562043015;6840.555363901331;6530.967345163243;6662.267150613526;7105.573665580082;7168.89246661311;7191.964040179766;3957.901052473596;4535.909281814411;4537.591616754508;4555.33947577606;4568.565986231302;5497.7355208301515;6072.8785424292155;6429.9678501607195;6687.910814536607;6976;6500.447461965597;6674.229060580757;6678.83302917431;6695.866082678416;6731.225909020525;5696;6713.966430167818;6720.260771315249;6760.830979225559;6793.912973924148;5715.342735585295;6556.704948277435;6575.704093315908;6576.934230657632;6606.696651674163;6279.901017322041;6594.670266486561;6612.074309596756;6705.262421133574;6731.1922569292;5707.400722924033;6027.939720602913;6456.838579035561;6523.445507131947;6523.543351965507 19:23:36 INFO - PID 1646 | |5;yui.html;834.5535138700794;834.7996480844599;848.1137211613859;866.0386970462786;874.9343799215059;14575.708485830277;15084.773728394082;16526.669466610674;16727;17184;16220.432284870032;17734.911325443387;17835.64328713425;17853.553661158476;17885.552861178476;955.6511873166279;966.0532677975588;972.377678285894;982.341830973246;986.4525188520404;971.7619183300102;983.5131609853097;983.5573991703726;986.8717066781309;989.6387818446287;938.5729493080632;1029.145808978546;1039.2777019971122;1039.4179259614602;1047.2773786087614;1031.9226058045676;1032.040202611571;1032.7263275232033;1032.8347464405658;1036.3781730961439;746.947713660044;754.5548126605329;755.8261599832015;757.0310003195918;758.1470845299039;14574.927125364306;15514.379424822993;17584.824151758447;17691.823081769147;17749.556261093447;17636.559086022822;17718.55703607407;18307.542311442186;18334.908325458287;18349.816501834943;14749.188794616291;15019.924900375538;15040.699186016327;15043;15095.24523773818;3472.4270495368487;3574.964250357489;3586.5337506124297;3616.367135751233;3617.0234036810175;121798.17302740466;126266.368668157;134452;139017;139489;13314.33428328599;13610.931945340408;13670.726585468434;13710.314484275747;13769.931150344184;25596.848141833663;26483.954345250157;26774.929002840057;26806.24712540246;26834;6611.404973552403;6658.400743933069;7526.209747976493;7736.226377362219;7760.34037106841;3798.2973149967343;3838.3858582627204;3858.0354911272184;3874.2445223181207;3874.302625527432;4183.226103170923;4255.1915136124035;4282.89292767677;4285.9713668719905;4289.849855255051;907.7821322882461;923.7136487688704;924.5469719837366;930.948797816113;943.8584212368199;620.461256084903;649.4252586461016;656.0782101147895;658.2627457247818;673.1148539670415;10966.890331096587;11613.477393517143;11867.525298987945;12164.75670486603;12294.446749896277;605.1497645807648;638.9361063893666;642.1363266406676;655.3517902475279;669.1301308299192;4450.732956022649;5532.640378375393;5546.473085056913;5652.66084034951;5801.970990145022;138016.6198338004;140251;140838.5916140846;142493;143624;121861.86036047332;121906.39046804709;122254;122876.38561807312;123365;102116;106780;107583;108081.91918080876;109872;11189.328640281608;11212.439378030902;11297.604583839526;11423.543058277908;11576.94211528937;35145.29709405747;44078.89802754957;44568;45398.09203815971;45916.622501325015;61566.69216653888;62015.37984620276;62094.68952655208;62285;62768.430789228805;14243.928780356031;14440;14460.27698615086;14707.632309192355;14708.338124784625;15673.294701738678;15708.293126809549;16290.755638665192;16336.264868080727;16443.835561644708;14978.550643480714;15069.835334579422;15206.543803685907;15293.694126117638;15348.693026139637;14477.565673029825;14675.192864392135;15077.170755608548;15172.544823655307;15193.164375958986;12577.93711031439;14853.18307493055;14853.554393368217;14914.328855201758;15154.545363639108;9081.727548173565;10474.162067034467;10531.631392901212;10607.363558186533;10907.509162087883;11004.889951100387;11770.470328835394;11778.528858845893;11852.051835853283;12096.334701591144 19:23:36 INFO - PID 1646 | __end_tp_report 19:23:36 INFO - PID 1646 | __start_cc_report 19:23:36 INFO - PID 1646 | _x_x_mozilla_cycle_collect,454 19:23:36 INFO - PID 1646 | __end_cc_report 19:23:36 INFO - PID 1646 | __startTimestamp1496370216840__endTimestamp 19:23:36 INFO - PID 1646 | 19:23:36 INFO - PID 1646 | ------- Summary: start ------- 19:23:36 INFO - PID 1646 | Number of tests: 6 19:23:36 INFO - PID 1646 | 19:23:36 INFO - PID 1646 | [#0] dojo.html Cycles:165 Average:6084.17 Median:6295.78 stddev:732.65 (11.6%) stddev-sans-first:706.73 19:23:36 INFO - PID 1646 | Values: 3519.6 3582.6 3600.3 3613.9 3628.7 4861.6 5522.8 6082.5 6169.1 6186.1 6099.9 6125.9 6138.2 6162.0 6217.3 4996.9 5261.4 5345.7 5388.1 5390.6 5076.7 5528.2 5541.8 5548.1 5572.7 5339.3 5400.4 5401.3 5451.9 5456.9 5452.0 5455.5 5456.8 5468.0 5487.3 5367.7 5397.5 5404.7 5415.3 5431.3 6069.5 6094.6 6201.7 6211.1 6227.2 6385.8 6554.1 6637.7 6663.8 6688.5 6602.9 6732.3 6741.5 6779.3 6799.2 6179.8 6267.1 6269.1 6283.7 6298.3 5624.9 6802.9 6877.1 6904.0 6908.1 5439.7 6293.4 6332.5 6538.7 6598.8 6702.8 6718.5 6729.4 6740.7 6762.3 6598.4 6622.9 6624.9 6624.9 6640.5 6406.7 6419.6 6455.5 6464.5 6587.5 5287.6 5315.9 5320.7 5322.7 5377.7 4207.6 4451.0 4725.1 4754.9 4781.4 6123.8 6131.6 6259.8 6268.2 6292.6 5925.8 6259.5 6672.9 6754.7 6778.3 6025.2 6272.8 6295.8 6299.5 6305.8 5747.4 5934.8 5947.9 5950.3 6004.0 6033.5 6740.2 6802.7 6874.5 6877.5 4970.9 6639.9 6715.4 6807.2 6823.2 6887.3 6909.5 6910.8 6923.7 6927.4 5376.9 6423.2 6489.9 6545.3 6587.6 6748.8 6754.0 6758.5 6759.6 6763.5 6300.5 6384.9 6544.7 6550.0 6553.0 6420.4 6454.4 6481.9 6514.6 6517.1 6312.7 6375.2 6375.2 6386.8 6400.6 6282.4 6494.5 6503.0 6517.8 6543.2 6247.3 6281.6 6298.6 6299.6 6349.3 19:23:36 INFO - PID 1646 | 19:23:36 INFO - PID 1646 | [#1] ext.html Cycles:180 Average:44183.75 Median:34857.63 stddev:77623.38 (222.7%) stddev-sans-first:77772.72 19:23:36 INFO - PID 1646 | Values: 778.7 809.9 836.7 1230.9 1301.5 36975.8 38211.0 38211.8 38234.4 38322.0 37462.9 38587.2 38629.6 38671.6 38706.8 6544.4 8031.1 8104.0 8327.1 8336.5 7009.9 7097.7 8311.7 8334.5 8336.8 8266.3 8304.6 8314.3 8341.2 8357.5 8226.5 8307.8 8343.6 8349.8 8359.9 8140.4 8174.9 8185.8 8190.5 8207.2 35842.0 36050.8 36055.8 36131.6 36351.3 3520.1 3562.6 3563.2 3563.8 3564.0 29996.3 30023.2 30062.5 30067.5 30083.2 3009.3 3042.9 3045.6 3065.7 3077.6 210311.9 211523.0 257144.0 279368.0 280141.0 42543.7 44168.0 46473.0 46506.8 46561.0 48168.0 48278.8 48292.8 49143.0 49858.8 13910.7 13982.7 14021.9 14153.0 14195.3 11192.4 11235.0 11313.9 11327.6 11351.6 13722.2 13763.1 13795.7 13804.9 13879.3 6174.5 6214.0 6236.2 6237.8 6262.8 987.9 1007.8 1024.4 1033.7 1069.8 23881.3 23917.9 24144.9 24160.0 24308.1 989.2 994.4 1068.0 1126.7 1151.8 12240.7 12429.6 12473.1 12488.4 12502.3 341180.0 344439.0 345601.0 345675.0 345793.3 52604.7 52718.7 52952.9 52981.0 53005.7 1477.2 1517.3 1544.5 1547.1 1549.8 22565.3 23293.0 23715.5 23798.4 23969.2 36511.5 36845.0 36850.9 36935.1 36965.8 281761.0 285012.0 285522.1 286346.7 286440.0 33708.3 34744.0 34971.3 35259.6 35314.1 42870.0 44227.1 44644.0 44688.1 44760.0 28499.1 28621.7 29500.6 30022.7 31010.0 27836.7 30074.8 30662.1 30789.0 31231.0 36072.1 36411.6 36475.9 36874.1 37336.8 30124.8 31128.5 31198.8 31290.4 31403.0 22085.0 23843.2 23969.0 24432.9 24465.1 19:23:36 INFO - PID 1646 | 19:23:36 INFO - PID 1646 | [#2] jquery.html Cycles:180 Average:37634.90 Median:2252.93 stddev:97193.46 (4314.1%) stddev-sans-first:97425.91 19:23:36 INFO - PID 1646 | Values: 400.3 406.8 419.8 433.2 455.5 14220.3 15061.5 21129.8 21917.5 21933.2 21820.1 22692.3 22962.8 23034.0 23035.8 5927.7 6080.6 6122.1 6122.5 6125.5 3535.9 3711.7 5976.6 6015.3 6109.2 5816.8 6115.8 6122.9 6126.8 6146.2 6066.5 6074.4 6097.5 6143.9 6148.4 5364.8 5393.5 5410.4 5416.1 5418.9 27177.1 27818.3 28048.0 28056.4 28121.0 2208.4 2253.9 2270.6 2274.2 2276.1 17806.6 18062.4 18192.2 18526.9 18656.0 1953.9 2120.2 2193.4 2204.3 2212.2 175307.1 176862.0 196090.0 198464.0 199765.0 10414.3 10611.7 10748.0 11334.7 12873.6 15921.4 15941.5 16050.4 16058.8 16098.2 8825.9 8899.2 9017.9 9035.4 9085.9 8704.6 8757.0 8767.4 8773.6 8794.6 6837.5 7406.3 7816.9 7932.5 8064.0 2240.1 2251.9 2254.9 2263.1 2433.1 928.7 930.3 975.8 981.8 1019.8 14805.7 16626.0 19897.3 19959.0 20428.6 849.4 993.3 1005.0 1017.9 1023.1 9753.9 9933.6 10491.5 10629.0 10726.9 556284.0 561634.0 564078.0 569429.0 569774.0 111483.0 144684.0 144911.3 146842.0 147490.0 1183.5 1183.8 1210.3 1211.9 1216.1 15778.2 17843.0 17847.6 18179.0 18210.8 46292.5 59745.0 61623.4 61989.7 62240.7 83011.8 83171.0 83642.0 83690.0 84119.0 7768.3 7988.2 9771.3 9832.3 10056.1 16970.4 17550.0 17762.0 17998.3 18095.3 14337.8 14572.5 14710.0 14797.6 14804.4 12798.7 13399.9 13589.8 13754.0 13808.2 15038.0 15097.7 15231.3 15251.8 15329.3 9983.8 10087.2 10151.1 10176.0 10218.1 14552.9 14703.6 14725.3 14843.9 14904.6 19:23:36 INFO - PID 1646 | 19:23:36 INFO - PID 1646 | [#3] mootools.html Cycles:180 Average:7202.06 Median:3901.93 stddev:4583.31 (117.5%) stddev-sans-first:4575.13 19:23:36 INFO - PID 1646 | Values: 1357.9 1424.6 1427.1 1430.4 1440.8 9485.8 11460.9 12188.9 12363.9 12399.1 12098.9 12195.8 12316.0 12326.5 12333.8 2538.7 2581.8 2596.1 2600.7 2604.9 2498.6 2514.9 2526.0 2530.8 2542.4 2505.7 2516.8 2527.5 2527.9 2529.3 2190.8 2388.7 2476.8 2489.1 2493.2 2000.5 2476.8 2481.5 2538.0 2542.7 11775.5 11812.9 11879.0 11891.3 11899.6 6439.2 6595.6 6702.9 6706.7 6707.7 3888.9 3958.6 4155.8 4184.8 4235.0 1188.7 1214.9 1230.9 1234.5 1250.8 15743.6 16116.4 16662.0 16725.8 16849.8 4877.9 5669.9 5819.0 5920.7 5944.2 12368.1 12572.5 12582.9 12584.2 12590.2 6316.6 6390.6 6495.1 6523.0 6523.9 5958.3 6008.3 6075.3 6141.3 6151.4 4377.0 4475.6 4578.4 4579.6 4614.8 1275.2 1285.3 1285.7 1299.8 1301.0 752.9 759.2 777.8 790.1 793.7 6643.7 6958.2 7078.7 7169.9 7266.7 852.1 905.7 917.9 941.3 963.5 3588.0 3915.0 4354.7 4402.2 4496.4 12063.9 15231.5 15250.9 15401.0 15490.4 14557.7 15197.8 15215.2 15216.6 15339.2 8516.4 8629.6 8689.8 8713.0 8733.5 5699.4 6855.5 7113.3 7164.9 7176.4 12960.0 13069.3 13261.4 13311.6 13755.9 14006.7 14302.5 14334.7 14393.4 14404.4 9233.3 9550.9 9616.1 9813.2 9854.6 11089.7 11156.0 11302.4 11319.5 11333.3 7643.6 9291.0 9372.0 9380.0 9483.7 9012.8 9097.3 9132.1 9239.0 9390.1 7921.0 8038.8 8046.8 8138.7 8143.8 5836.1 5885.8 5948.5 5991.2 6034.2 5964.1 5983.1 6034.7 6268.1 6333.2 19:23:36 INFO - PID 1646 | 19:23:36 INFO - PID 1646 | [#4] prototype.html Cycles:180 Average:5332.36 Median:6008.14 stddev:1698.10 (28.3%) stddev-sans-first:1661.36 19:23:36 INFO - PID 1646 | Values: 360.7 394.6 395.9 399.7 403.3 5945.2 6296.3 6303.2 6354.9 6375.8 5066.4 6125.5 6131.0 6152.7 6163.8 3683.8 4486.2 4530.6 4549.7 4549.8 4232.4 4522.5 4535.5 4559.6 4581.4 4483.3 4498.8 4512.9 4522.0 4530.6 4137.4 4242.9 4501.4 4516.5 4517.2 3459.5 3900.6 3905.0 3920.5 3928.0 5978.2 6006.1 6010.2 6011.7 6036.9 4744.5 4909.7 5406.0 5407.6 5432.4 5233.9 5709.0 6599.9 6601.8 6635.5 4446.2 4452.9 4464.6 4473.9 4495.6 5015.7 6434.9 6738.0 6919.0 7092.8 3799.8 4426.4 4521.4 4549.4 4560.8 3891.5 4604.5 4621.5 4628.4 4648.8 4481.5 5418.9 5436.0 5437.7 5443.8 5902.2 6300.5 6666.0 6679.3 6691.3 1325.8 1567.3 1580.3 1587.0 1594.4 776.4 1041.6 1044.5 1050.1 1055.0 5281.3 6294.8 6316.9 6332.0 6344.0 6894.3 6940.5 6945.7 6956.8 6965.0 5509.6 6262.4 6262.7 6270.1 6305.4 3140.8 3471.3 3586.4 3615.4 3621.8 6170.6 7126.9 7135.5 7159.9 7189.9 5889.1 6207.8 7115.6 7140.1 7144.2 4969.7 7028.4 7105.5 7140.6 7177.5 6305.8 6807.3 6878.8 6916.5 6950.8 5738.6 6815.8 6820.3 6827.4 6840.6 6531.0 6662.3 7105.6 7168.9 7192.0 3957.9 4535.9 4537.6 4555.3 4568.6 5497.7 6072.9 6430.0 6687.9 6976.0 6500.4 6674.2 6678.8 6695.9 6731.2 5696.0 6714.0 6720.3 6760.8 6793.9 5715.3 6556.7 6575.7 6576.9 6606.7 6279.9 6594.7 6612.1 6705.3 6731.2 5707.4 6027.9 6456.8 6523.4 6523.5 19:23:36 INFO - PID 1646 | 19:23:36 INFO - PID 1646 | [#5] yui.html Cycles:180 Average:24543.41 Median:17384.41 stddev:38095.83 (219.1%) stddev-sans-first:38161.10 19:23:36 INFO - PID 1646 | Values: 834.6 834.8 848.1 866.0 874.9 14575.7 15084.8 16526.7 16727.0 17184.0 16220.4 17734.9 17835.6 17853.6 17885.6 955.7 966.1 972.4 982.3 986.5 971.8 983.5 983.6 986.9 989.6 938.6 1029.1 1039.3 1039.4 1047.3 1031.9 1032.0 1032.7 1032.8 1036.4 746.9 754.6 755.8 757.0 758.1 14574.9 15514.4 17584.8 17691.8 17749.6 17636.6 17718.6 18307.5 18334.9 18349.8 14749.2 15019.9 15040.7 15043.0 15095.2 3472.4 3575.0 3586.5 3616.4 3617.0 121798.2 126266.4 134452.0 139017.0 139489.0 13314.3 13610.9 13670.7 13710.3 13769.9 25596.8 26484.0 26774.9 26806.2 26834.0 6611.4 6658.4 7526.2 7736.2 7760.3 3798.3 3838.4 3858.0 3874.2 3874.3 4183.2 4255.2 4282.9 4286.0 4289.8 907.8 923.7 924.5 930.9 943.9 620.5 649.4 656.1 658.3 673.1 10966.9 11613.5 11867.5 12164.8 12294.4 605.1 638.9 642.1 655.4 669.1 4450.7 5532.6 5546.5 5652.7 5802.0 138016.6 140251.0 140838.6 142493.0 143624.0 121861.9 121906.4 122254.0 122876.4 123365.0 102116.0 106780.0 107583.0 108081.9 109872.0 11189.3 11212.4 11297.6 11423.5 11576.9 35145.3 44078.9 44568.0 45398.1 45916.6 61566.7 62015.4 62094.7 62285.0 62768.4 14243.9 14440.0 14460.3 14707.6 14708.3 15673.3 15708.3 16290.8 16336.3 16443.8 14978.6 15069.8 15206.5 15293.7 15348.7 14477.6 14675.2 15077.2 15172.5 15193.2 12577.9 14853.2 14853.6 14914.3 15154.5 9081.7 10474.2 10531.6 10607.4 10907.5 11004.9 11770.5 11778.5 11852.1 12096.3 19:23:36 INFO - PID 1646 | -------- Summary: end -------- 19:23:36 INFO - PID 1646 | 19:23:37 INFO - TEST-INFO | 1646: exit 0 19:23:37 INFO - TEST-OK | dromaeo_css | took 1143267ms 19:23:37 INFO - TEST-START | kraken 19:23:37 INFO - operating with platform_type : mac_ 19:23:37 INFO - Initialising browser for kraken test... 19:23:37 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmp4TS7au/profile 19:23:37 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 19:24:11 INFO - TEST-INFO | 1656: killed by SIGTERM 19:24:11 INFO - Browser initialized. 19:24:11 INFO - Running cycle 1/1 for kraken test... 19:24:11 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmp4TS7au/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:24:13 INFO - PID 1660 | 2017-06-01 19:24:13.814 plugin-container[1661:10593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3f, name = 'com.apple.tsm.portname' 19:24:13 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:24:13 INFO - PID 1660 | 2017-06-01 19:24:13.815 plugin-container[1661:10593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b43, name = 'com.apple.CFPasteboardClient' 19:24:13 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:24:13 INFO - PID 1660 | 2017-06-01 19:24:13.815 plugin-container[1661:10593] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:24:14 INFO - PID 1660 | 2017-06-01 19:24:14.218 plugin-container[1662:10641] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x483f, name = 'com.apple.tsm.portname' 19:24:14 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:24:14 INFO - PID 1660 | 2017-06-01 19:24:14.219 plugin-container[1662:10641] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x490f, name = 'com.apple.CFPasteboardClient' 19:24:14 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:24:14 INFO - PID 1660 | 2017-06-01 19:24:14.219 plugin-container[1662:10641] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:24:23 INFO - PID 1660 | RSS: Main: 268431360 19:24:23 INFO - PID 1660 | 19:24:26 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 19:24:27 INFO - PID 1660 | RSS: Main: 276258816 19:24:27 INFO - PID 1660 | 19:24:29 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 19:24:30 INFO - PID 1660 | RSS: Main: 283922432 19:24:30 INFO - PID 1660 | 19:24:33 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 19:24:34 INFO - PID 1660 | RSS: Main: 281780224 19:24:34 INFO - PID 1660 | 19:24:36 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 19:24:37 INFO - PID 1660 | RSS: Main: 284045312 19:24:37 INFO - PID 1660 | 19:24:39 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:24:39 INFO - PID 1660 | RSS: Main: 284557312 19:24:39 INFO - PID 1660 | 19:24:42 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 19:24:43 INFO - PID 1660 | RSS: Main: 289091584 19:24:43 INFO - PID 1660 | 19:24:46 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 19:24:46 INFO - PID 1660 | RSS: Main: 291901440 19:24:46 INFO - PID 1660 | 19:24:49 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 19:24:50 INFO - PID 1660 | RSS: Main: 291233792 19:24:50 INFO - PID 1660 | 19:24:52 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:24:52 INFO - PID 1660 | RSS: Main: 290795520 19:24:52 INFO - PID 1660 | 19:24:54 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:24:55 INFO - PID 1660 | RSS: Main: 290873344 19:24:55 INFO - PID 1660 | 19:24:57 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:24:58 INFO - PID 1660 | RSS: Main: 290717696 19:24:58 INFO - PID 1660 | 19:25:01 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:25:02 INFO - PID 1660 | RSS: Main: 290603008 19:25:02 INFO - PID 1660 | 19:25:05 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:25:05 INFO - PID 1660 | RSS: Main: 291008512 19:25:05 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 19:25:07 INFO - PID 1660 | RSS: Main: 292384768 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | __start_tp_report 19:25:07 INFO - PID 1660 | _x_x_mozilla_page_load 19:25:07 INFO - PID 1660 | _x_x_mozilla_page_load_details 19:25:07 INFO - PID 1660 | |i|pagename|runs| 19:25:07 INFO - PID 1660 | |0;ai-astar;100;140;168;155;114;115;114;114;112;113 19:25:07 INFO - PID 1660 | |1;audio-beat-detection;134;111;111;140;101;110;108;113;138;112 19:25:07 INFO - PID 1660 | |2;audio-dft;199;201;206;222;208;227;195;196;203;236 19:25:07 INFO - PID 1660 | |3;audio-fft;79;52;57;91;88;85;84;57;79;56 19:25:07 INFO - PID 1660 | |4;audio-oscillator;74;68;85;72;70;70;87;71;89;125 19:25:07 INFO - PID 1660 | |5;imaging-gaussian-blur;91;90;91;90;90;91;90;91;90;91 19:25:07 INFO - PID 1660 | |6;imaging-darkroom;97;98;97;97;98;98;96;106;104;99 19:25:07 INFO - PID 1660 | |7;imaging-desaturate;92;93;90;92;118;139;98;145;140;89 19:25:07 INFO - PID 1660 | |8;json-parse-financial;52;50;47;67;76;60;49;52;51;50 19:25:07 INFO - PID 1660 | |9;json-stringify-tinderbox;59;65;52;65;97;89;57;46;74;81 19:25:07 INFO - PID 1660 | |10;stanford-crypto-aes;88;99;111;118;77;111;116;78;122;78 19:25:07 INFO - PID 1660 | |11;stanford-crypto-ccm;138;165;154;135;139;142;128;127;144;160 19:25:07 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;195;192;191;191;186;188;187;183;188;191 19:25:07 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;68;66;66;67;70;68;63;85;77;68 19:25:07 INFO - PID 1660 | __end_tp_report 19:25:07 INFO - PID 1660 | __start_cc_report 19:25:07 INFO - PID 1660 | _x_x_mozilla_cycle_collect,1202 19:25:07 INFO - PID 1660 | __end_cc_report 19:25:07 INFO - PID 1660 | __startTimestamp1496370307722__endTimestamp 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | ------- Summary: start ------- 19:25:07 INFO - PID 1660 | Number of tests: 14 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:124.50 Median:114.50 stddev:22.04 (19.3%) stddev-sans-first:21.52 19:25:07 INFO - PID 1660 | Values: 100.0 140.0 168.0 155.0 114.0 115.0 114.0 114.0 112.0 113.0 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:117.80 Median:112.50 stddev:13.95 (12.4%) stddev-sans-first:13.51 19:25:07 INFO - PID 1660 | Values: 134.0 111.0 111.0 140.0 101.0 110.0 108.0 113.0 138.0 112.0 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:209.30 Median:207.00 stddev:14.13 (6.8%) stddev-sans-first:14.48 19:25:07 INFO - PID 1660 | Values: 199.0 201.0 206.0 222.0 208.0 227.0 195.0 196.0 203.0 236.0 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:72.80 Median:81.50 stddev:15.38 (18.9%) stddev-sans-first:16.14 19:25:07 INFO - PID 1660 | Values: 79.0 52.0 57.0 91.0 88.0 85.0 84.0 57.0 79.0 56.0 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:73.00 stddev:17.30 (23.7%) stddev-sans-first:18.16 19:25:07 INFO - PID 1660 | Values: 74.0 68.0 85.0 72.0 70.0 70.0 87.0 71.0 89.0 125.0 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:91.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 19:25:07 INFO - PID 1660 | Values: 91.0 90.0 91.0 90.0 90.0 91.0 90.0 91.0 90.0 91.0 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:99.00 Median:97.50 stddev:3.30 (3.4%) stddev-sans-first:3.42 19:25:07 INFO - PID 1660 | Values: 97.0 98.0 97.0 97.0 98.0 98.0 96.0 106.0 104.0 99.0 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:109.60 Median:91.00 stddev:23.45 (25.8%) stddev-sans-first:24.00 19:25:07 INFO - PID 1660 | Values: 92.0 93.0 90.0 92.0 118.0 139.0 98.0 145.0 140.0 89.0 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:52.00 stddev:9.38 (18.0%) stddev-sans-first:9.87 19:25:07 INFO - PID 1660 | Values: 52.0 50.0 47.0 67.0 76.0 60.0 49.0 52.0 51.0 50.0 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:69.50 stddev:16.48 (23.7%) stddev-sans-first:17.12 19:25:07 INFO - PID 1660 | Values: 59.0 65.0 52.0 65.0 97.0 89.0 57.0 46.0 74.0 81.0 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:99.80 Median:77.50 stddev:18.10 (23.4%) stddev-sans-first:18.68 19:25:07 INFO - PID 1660 | Values: 88.0 99.0 111.0 118.0 77.0 111.0 116.0 78.0 122.0 78.0 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:143.20 Median:143.00 stddev:12.83 (9.0%) stddev-sans-first:13.47 19:25:07 INFO - PID 1660 | Values: 138.0 165.0 154.0 135.0 139.0 142.0 128.0 127.0 144.0 160.0 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.20 Median:191.00 stddev:3.46 (1.8%) stddev-sans-first:2.96 19:25:07 INFO - PID 1660 | Values: 195.0 192.0 191.0 191.0 186.0 188.0 187.0 183.0 188.0 191.0 19:25:07 INFO - PID 1660 | 19:25:07 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:68.00 stddev:6.46 (9.5%) stddev-sans-first:6.82 19:25:07 INFO - PID 1660 | Values: 68.0 66.0 66.0 67.0 70.0 68.0 63.0 85.0 77.0 68.0 19:25:07 INFO - PID 1660 | -------- Summary: end -------- 19:25:07 INFO - PID 1660 | 19:25:08 INFO - TEST-INFO | 1660: exit 0 19:25:08 INFO - TEST-OK | kraken | took 91143ms 19:25:08 INFO - SUITE-END | took 1234s 19:25:08 INFO - Completed test suite (00:20:34) 19:25:08 INFO - javascript benchmark 19:25:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7916.425611255159, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3519.577650681918, 3582.5700915890097, 3600.315939971407, 3613.945790813138, 3628.727845411595, 4861.586765124968, 5522.784987302792, 6082.482988945945, 6169.074638804182, 6186.072089186624, 6099.908501372472, 6125.937554508985, 6138.173828368832, 6162.0, 6217.253929528463, 4996.850094497171, 5261.404930330137, 5345.652532585389, 5388.110961691316, 5390.6226564140625, 5076.68006318358, 5528.198411230369, 5541.806036788713, 5548.112302031675, 5572.749226284806, 5339.279197308352, 5400.351957765054, 5401.297831281908, 5451.863703407446, 5456.890862182754, 5451.964126815892, 5455.509004189602, 5456.836294911159, 5468.043092458804, 5487.341519017744, 5367.651102678314, 5397.46025397461, 5404.729763511808, 5415.268938693265, 5431.348238211401, 6069.544784141163, 6094.573379863411, 6201.658908760016, 6211.130441738161, 6227.159333489966, 6385.7764978226005, 6554.147960765118, 6637.73449062037, 6663.800085997428, 6688.4649228061635, 6602.910519764233, 6732.259451460335, 6741.528093033489, 6779.32206779323, 6799.218089919673, 6179.752809887644, 6267.091271765568, 6269.059641053787, 6283.6858157091965, 6298.307186209513, 5624.887502249932, 6802.93197068023, 6877.140357455318, 6904.033435319059, 6908.0674108995645, 5439.728013599304, 6293.402126798039, 6332.461740751995, 6538.705758240891, 6598.769043083565, 6702.798916032527, 6718.49611279156, 6729.427998620172, 6740.662966851638, 6762.289959554274, 6598.43913267368, 6622.86754264912, 6624.86750264992, 6624.867502650017, 6640.501962352843, 6406.67966601668, 6419.550631455847, 6455.4835613150835, 6464.450521705708, 6587.538872278895, 5287.603429742785, 5315.893682126413, 5320.680759154463, 5322.733863306897, 5377.650452720639, 4207.600277973587, 4451.0430257494145, 4725.10223057618, 4754.857354279377, 4781.378420805273, 6123.8162855114415, 6131.590761710035, 6259.78090766821, 6268.153799237136, 6292.559520833589, 5925.762969481172, 6259.4679452246155, 6672.866542669217, 6754.662266886636, 6778.322167783281, 6025.186599809057, 6272.8118156455375, 6295.779647712308, 6299.464545513591, 6305.8108256752375, 5747.4252574742195, 5934.7922822701, 5947.881042379128, 5950.285965684146, 6004.0, 6033.517318614413, 6740.224874139439, 6802.693878775467, 6874.484413668894, 6877.518573699793, 4970.925436118533, 6639.933600664125, 6715.395614394728, 6807.21717002551, 6823.249442561413, 6887.276835932155, 6909.481788865955, 6910.7581234656545, 6923.6805670101085, 6927.44580433554, 5376.892462150813, 6423.197100362455, 6489.902651460137, 6545.312742162195, 6587.604743715392, 6748.763793267212, 6753.966230169014, 6758.4931130166415, 6759.5606285591275, 6763.458923286224, 6300.527460440394, 6384.904226436514, 6544.738210471528, 6550.0, 6553.0170474429215, 6420.390062944013, 6454.419102280817, 6481.902771458526, 6514.609123452608, 6517.055027021151, 6312.652804095819, 6375.151206150164, 6375.234971803413, 6386.840328991812, 6400.647964362005, 6282.371762823681, 6494.545381823227, 6502.967485162733, 6517.804465866032, 6543.1821022372205, 6247.250329960434, 6281.560290779602, 6298.559100862895, 6299.590526615939, 6349.333320001518], "value": 6037.666425511441, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.6885245901639, 809.8582748019097, 836.6987884361629, 1230.8646048934622, 1301.5314486784762, 36975.81512092439, 38210.99999999996, 38211.808940955256, 38234.426483602765, 38322.0, 37462.876113716564, 38587.22825543487, 38629.61370386295, 38671.613283867155, 38706.806465967704, 6544.3782840630065, 8031.076426210973, 8103.95948020262, 8327.125651806564, 8336.541490218066, 7009.929900701004, 7097.716091356339, 8311.709090181814, 8334.541600212016, 8336.833263334729, 8266.338692904554, 8304.584770761438, 8314.251717345438, 8341.165883411672, 8357.456765310266, 8226.506409615442, 8307.83384332313, 8343.624536895853, 8349.8330033399, 8359.874601880976, 8140.389470789655, 8174.877376839352, 8185.754427367188, 8190.467619604707, 8207.179282071746, 35842.0, 36050.81974590137, 36055.81972090149, 36131.63868361309, 36351.27297454036, 3520.119970007498, 3562.5546806649168, 3563.2339047104997, 3563.8396272167815, 3564.0, 29996.250093747614, 30023.249418764488, 30062.54906176409, 30067.548986765218, 30083.247918801986, 3009.277773334406, 3042.878284868603, 3045.649750278702, 3065.73941214997, 3077.5999120114243, 210311.94844025836, 211523.0, 257143.99999999814, 279368.0, 280141.0, 42543.7236882894, 44168.0, 46473.0, 46506.76746616313, 46561.0, 48168.0, 48278.75860620745, 48292.758536207795, 49143.0, 49858.75070624624, 13910.721785564434, 13982.650433739238, 14021.85978140226, 14153.0, 14195.290235488184, 11192.440377981231, 11235.0, 11313.943430282796, 11327.60353387644, 11351.60269390584, 13722.176669399669, 13763.105398149288, 13795.655108622364, 13804.861951380559, 13879.306034698224, 6174.475169610543, 6214.0, 6236.220472440945, 6237.781677641261, 6262.843428914222, 987.9209663226925, 1007.808516381885, 1024.3802499487851, 1033.6588925654517, 1069.7593041565585, 23881.283561493183, 23917.880410597834, 24144.87927560386, 24160.0, 24308.14921477775, 989.1690979577189, 994.3636072913341, 1067.990748742431, 1126.6507382711386, 1151.758130792543, 12240.693982650506, 12429.627111186679, 12473.064520160842, 12488.375581220902, 12502.3123728194, 341180.0, 344439.0, 345601.0, 345675.0, 345793.2710336432, 52604.736976314874, 52718.73640631849, 52952.94094118173, 52981.0, 53005.73497132567, 1477.1875468492287, 1517.286875168647, 1544.4594391962723, 1547.1104115133844, 1549.8140223173066, 22565.323040308816, 23292.951817168607, 23715.52568948577, 23798.405039874142, 23969.16107936214, 36511.45232821457, 36845.0, 36850.89447316585, 36935.076623083565, 36965.815170923976, 281761.0, 285012.0, 285522.14477854955, 286346.70479943237, 286440.0, 33708.325833482704, 34743.9576812696, 34971.30057398888, 35259.64740352564, 35314.11714707153, 42870.0, 44227.11545769131, 44644.0, 44688.10623787441, 44760.0, 28499.145025649264, 28621.71378286274, 29500.627151930883, 30022.69977300199, 31010.0, 27836.74734636906, 30074.849625751733, 30662.08013759591, 30789.0, 31231.0, 36072.06454372747, 36411.635883640825, 36475.89337492806, 36874.07814804544, 37336.81331593324, 30124.84937575298, 31128.489624167458, 31198.844005779825, 31290.380473797813, 31403.0, 22085.0, 23843.165489207793, 23969.0, 24432.877835610707, 24465.143719969714], "value": 17292.962601756444, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.27550134256995, 406.808799864064, 419.771224682548, 433.1920967395807, 455.510326399121, 14220.288985550733, 15061.472848450307, 21129.788702112975, 21917.452063698394, 21933.232336868212, 21820.127194912224, 22692.319230423112, 22962.770372296272, 23033.963471643776, 23035.76964230357, 5927.673977931212, 6080.574359794816, 6122.0816877468515, 6122.47958923492, 6125.540584456184, 3535.9116022099524, 3711.7401781875146, 5976.581639285252, 6015.308239552442, 6109.205803245572, 5816.767329306823, 6115.816525504242, 6122.877542449149, 6126.7855625053135, 6146.170267013963, 6066.484348830355, 6074.42292982166, 6097.475631092249, 6143.87712245757, 6148.38516148386, 5364.8122315718765, 5393.514583687448, 5410.431904649994, 5416.0792665247, 5418.891622167574, 27177.118635595652, 27818.304542386602, 28048.0, 28056.438871222665, 28121.0, 2208.3595757230382, 2253.9436514087115, 2270.579942710604, 2274.1926616051396, 2276.112316196685, 17806.55483612907, 18062.36781712647, 18192.181351839197, 18526.907365463223, 18655.973921434317, 1953.9316123935737, 2120.2155202575145, 2193.385851961453, 2204.3166618348364, 2212.192549719357, 175307.12346438188, 176862.0, 196090.0, 198464.0, 199765.0, 10414.323069000491, 10611.681649550526, 10748.0, 11334.659960201207, 12873.613791586267, 15921.363145474283, 15941.521754347388, 16050.438234661731, 16058.75911861323, 16098.195090245441, 8825.867611985825, 8899.199072083544, 9017.864732029026, 9035.412698174598, 9085.909140908638, 8704.564771761387, 8756.95621521901, 8767.430117042371, 8773.561321934005, 8794.558747832303, 6837.487188460886, 7406.259374062551, 7816.8827467588035, 7932.524048557105, 8064.032316122103, 2240.05917514641, 2251.90992360307, 2254.8872556372116, 2263.1286954522297, 2433.051110067075, 928.6981730937472, 930.3115694386106, 975.8243516167158, 981.788915383196, 1019.8368261078192, 14805.741511971388, 16626.0, 19897.303594374127, 19959.0, 20428.59142817165, 849.4478588917129, 993.3493561717087, 1004.9602534247549, 1017.9491025448846, 1023.1098943918689, 9753.87830399499, 9933.602655893683, 10491.527881245362, 10629.043386095289, 10726.946365268124, 556284.0, 561634.0, 564078.0, 569429.0, 569774.0, 111483.0, 144684.0, 144911.27544362211, 146842.0, 147490.0, 1183.479269121601, 1183.7573297473998, 1210.3222195570509, 1211.9454624541918, 1216.1183142221819, 15778.211089445711, 17843.0, 17847.553811154827, 18179.0, 18210.817891820912, 46292.53707463017, 59745.0, 61623.383766161765, 61989.69005155126, 62240.688796555725, 83011.7548236789, 83171.0, 83642.0, 83690.0, 84119.0, 7768.262015108556, 7988.241117093867, 9771.267154963263, 9832.311738178545, 10056.089343024802, 16970.406035789183, 17550.0, 17762.0, 17998.280068797103, 18095.276188952295, 14337.78493322622, 14572.489962851248, 14710.0438471499, 14797.556073317817, 14804.407823687363, 12798.744025119258, 13399.866001339862, 13589.796153057514, 13754.0, 13808.240546770025, 15038.0, 15097.698046039237, 15231.314590843216, 15251.771223431435, 15329.310181041657, 9983.752030996126, 10087.193024557871, 10151.08640222354, 10176.0, 10218.131458826083, 14552.927235363755, 14703.55889323322, 14725.263736812902, 14843.92578037103, 14904.552863414116], "value": 10744.230063041736, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.8710022547857, 1424.5512663510992, 1427.1437137717378, 1430.4349781836174, 1440.7766796146598, 9485.76685030946, 11460.942695286512, 12188.939055304732, 12363.876361236375, 12399.070069744772, 12098.879011209885, 12195.75608487832, 12316.014718822495, 12326.5069397224, 12333.814992775093, 2538.6699729035245, 2581.8321809082445, 2596.117320111162, 2600.700919394275, 2604.895804167831, 2498.5502609530276, 2514.924552263426, 2526.027479420427, 2530.7595778401023, 2542.364408897776, 2505.7368976257503, 2516.7609077137645, 2527.545041892459, 2527.8609676467786, 2529.253870108313, 2190.8247340212743, 2388.6623490845095, 2476.826622136442, 2489.0914816092177, 2493.1897133431708, 2000.4998750312423, 2476.8018558515278, 2481.491294284667, 2538.035546492321, 2542.733013033623, 11775.52897884092, 11812.881871181264, 11879.0, 11891.345975971319, 11899.643010709693, 6439.227292724856, 6595.6372399518, 6702.932970670279, 6706.698198581027, 6707.731690732365, 3888.8833334999995, 3958.5843486433796, 4155.750654960712, 4184.832606695729, 4234.978825105885, 1188.6671731915078, 1214.878512148787, 1230.919990200643, 1234.481517762542, 1250.8498980122351, 15743.606409839731, 16116.435924742695, 16662.0, 16725.832741672548, 16849.83150168495, 4877.878053048702, 5669.914951275734, 5819.010768169422, 5920.703964801829, 5944.227250457413, 12368.072394570443, 12572.497100116076, 12582.87417125817, 12584.244945303311, 12590.24458532491, 6316.557840951181, 6390.616563006234, 6495.090687303781, 6522.967385163044, 6523.934760652428, 5958.285005799332, 6008.309044459856, 6075.33171351151, 6141.324454310023, 6151.446369826737, 4376.993291542963, 4475.619572336323, 4578.40480737502, 4579.610733087725, 4614.7770840727235, 1275.1966261255536, 1285.2995117660898, 1285.717142228708, 1299.7660421124099, 1301.0437328563498, 752.9360004399578, 759.2255898983007, 777.8327659553223, 790.1387487638501, 793.7023616143946, 6643.734250630017, 6958.165020197507, 7078.68145933424, 7169.8811017206635, 7266.7093316266755, 852.1308265569209, 905.6784841381346, 917.8898532176181, 941.3128416256171, 963.5086106085946, 3587.982060089683, 3914.9804250978564, 4354.738715677069, 4402.185595664812, 4496.4154659894, 12063.879361206276, 15231.543053708407, 15250.923745381424, 15401.0, 15490.380384784708, 14557.708845823025, 15197.772033419507, 15215.239238038053, 15216.619584510254, 15339.156346401054, 8516.361272904556, 8629.61166747498, 8689.782755431164, 8713.0, 8733.475991440533, 5699.430056994267, 6855.520113592001, 7113.253108423541, 7164.928350716426, 7176.354128128493, 12960.0, 13069.281189534668, 13261.403236854558, 13311.600651980456, 13755.862441375459, 14006.719865602427, 14302.499412520512, 14334.713305734036, 14393.424263029361, 14404.351804169048, 9233.261339092991, 9550.90449095528, 9616.08647178517, 9813.165880900204, 9854.605815767576, 11089.722756931142, 11156.0, 11302.378369189773, 11319.490622922152, 11333.26333788301, 7643.617819109134, 9291.024442433447, 9372.015938326651, 9380.0, 9483.715488535356, 9012.81974360496, 9097.317701172306, 9132.132447417485, 9238.9538052312, 9390.060993900555, 7920.960395197987, 8038.83922321562, 8046.83906321882, 8138.715144969898, 8143.83712325762, 5836.095405212199, 5885.793997210077, 5948.524118070631, 5991.191189189578, 6034.185384972972, 5964.105384192406, 5983.072623743326, 6034.7284372202475, 6268.093517279809, 6333.1766870306565], "value": 5422.158656823692, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.71863946122, 394.61130786175613, 395.9307121253781, 399.7281848343126, 403.3296997324348, 5945.167676525284, 6296.338884417139, 6303.180586523757, 6354.904676429857, 6375.847307348407, 5066.3920329560415, 6125.540584456173, 6131.0, 6152.723127459264, 6163.753449862, 3683.8158092095423, 4486.170058539173, 4530.569595888385, 4549.658775591827, 4549.7725113744345, 4232.38630398592, 4522.47991480979, 4535.478419981695, 4559.635229181657, 4581.427321584802, 4483.327500874862, 4498.775061246941, 4512.932306015412, 4522.0, 4530.614897733697, 4137.358709400048, 4242.851500197494, 4501.392312037899, 4516.4806047304655, 4517.186906356855, 3459.4810778383194, 3900.6099390060767, 3905.0, 3920.5491368492703, 3927.960720392788, 5978.192943952554, 6006.12911127884, 6010.158577799111, 6011.729472173719, 6036.879262414771, 4744.501827308118, 4909.69892978361, 5406.0, 5407.648502847303, 5432.375276843135, 5233.921491177635, 5709.0, 6599.868002639921, 6601.834954126137, 6635.5023373247195, 4446.177457170402, 4452.933206001912, 4464.575865292792, 4473.865784026484, 4495.640348772123, 5015.699058056492, 6434.906065968749, 6737.978606418104, 6918.993491887347, 7092.787216383516, 3799.7910114943943, 4426.44669416323, 4521.412216411845, 4549.431321084864, 4560.794764235618, 3891.474650922146, 4604.470485894098, 4621.537846215351, 4628.421447319085, 4648.790804413809, 4481.529439408815, 5418.945810541844, 5436.0, 5437.673739575638, 5443.836684899459, 5902.2327097477055, 6300.527460440394, 6666.033425153326, 6679.298673639198, 6691.297413771581, 1325.821014163095, 1567.2790516362602, 1580.3125640346257, 1586.9682606347808, 1594.3702237616076, 776.4370831147486, 1041.5833666533297, 1044.5351818440872, 1050.0864248104199, 1055.0, 5281.339832520935, 6294.842628934313, 6316.936830631635, 6332.0, 6344.0, 6894.345037221456, 6940.479464040217, 6945.652717364112, 6956.826079347956, 6965.024896514492, 5509.614326997152, 6262.432402714865, 6262.749490020439, 6270.059491076375, 6305.369463053658, 3140.811551306929, 3471.3057388522398, 3586.4261718124976, 3615.4215325547784, 3621.764585301947, 6170.562627762009, 7126.928730712627, 7135.464840136907, 7159.92840071603, 7189.921511773171, 5889.057750759858, 6207.844803879939, 7115.576884623096, 7140.107486564179, 7144.1784194818265, 4969.676970996874, 7028.402585780266, 7105.502614817016, 7140.607266600283, 7177.4616903731385, 6305.842353941188, 6807.251202367835, 6878.828029299308, 6916.51584389108, 6950.805915877043, 5738.5696072795545, 6815.761448349483, 6820.283870193558, 6827.395562043015, 6840.555363901331, 6530.967345163243, 6662.267150613526, 7105.573665580082, 7168.89246661311, 7191.964040179766, 3957.901052473596, 4535.909281814411, 4537.591616754508, 4555.33947577606, 4568.565986231302, 5497.7355208301515, 6072.8785424292155, 6429.9678501607195, 6687.910814536607, 6976.0, 6500.447461965597, 6674.229060580757, 6678.83302917431, 6695.866082678416, 6731.225909020525, 5696.0, 6713.966430167818, 6720.260771315249, 6760.830979225559, 6793.912973924148, 5715.342735585295, 6556.704948277435, 6575.704093315908, 6576.934230657632, 6606.696651674163, 6279.901017322041, 6594.670266486561, 6612.074309596756, 6705.262421133574, 6731.1922569292, 5707.400722924033, 6027.939720602913, 6456.838579035561, 6523.445507131947, 6523.543351965507], "value": 4800.422697686843, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.5535138700794, 834.7996480844599, 848.1137211613859, 866.0386970462786, 874.9343799215059, 14575.708485830277, 15084.773728394082, 16526.669466610674, 16727.0, 17184.0, 16220.432284870032, 17734.911325443387, 17835.64328713425, 17853.553661158476, 17885.552861178476, 955.6511873166279, 966.0532677975588, 972.377678285894, 982.341830973246, 986.4525188520404, 971.7619183300102, 983.5131609853097, 983.5573991703726, 986.8717066781309, 989.6387818446287, 938.5729493080632, 1029.145808978546, 1039.2777019971122, 1039.4179259614602, 1047.2773786087614, 1031.9226058045676, 1032.040202611571, 1032.7263275232033, 1032.8347464405658, 1036.3781730961439, 746.947713660044, 754.5548126605329, 755.8261599832015, 757.0310003195918, 758.1470845299039, 14574.927125364306, 15514.379424822993, 17584.824151758447, 17691.823081769147, 17749.556261093447, 17636.559086022822, 17718.55703607407, 18307.542311442186, 18334.908325458287, 18349.816501834943, 14749.188794616291, 15019.924900375538, 15040.699186016327, 15043.0, 15095.24523773818, 3472.4270495368487, 3574.964250357489, 3586.5337506124297, 3616.367135751233, 3617.0234036810175, 121798.17302740466, 126266.368668157, 134452.0, 139017.0, 139489.0, 13314.33428328599, 13610.931945340408, 13670.726585468434, 13710.314484275747, 13769.931150344184, 25596.848141833663, 26483.954345250157, 26774.929002840057, 26806.24712540246, 26834.0, 6611.404973552403, 6658.400743933069, 7526.209747976493, 7736.226377362219, 7760.34037106841, 3798.2973149967343, 3838.3858582627204, 3858.0354911272184, 3874.2445223181207, 3874.302625527432, 4183.226103170923, 4255.1915136124035, 4282.89292767677, 4285.9713668719905, 4289.849855255051, 907.7821322882461, 923.7136487688704, 924.5469719837366, 930.948797816113, 943.8584212368199, 620.461256084903, 649.4252586461016, 656.0782101147895, 658.2627457247818, 673.1148539670415, 10966.890331096587, 11613.477393517143, 11867.525298987945, 12164.75670486603, 12294.446749896277, 605.1497645807648, 638.9361063893666, 642.1363266406676, 655.3517902475279, 669.1301308299192, 4450.732956022649, 5532.640378375393, 5546.473085056913, 5652.66084034951, 5801.970990145022, 138016.6198338004, 140251.0, 140838.5916140846, 142493.0, 143624.0, 121861.86036047332, 121906.39046804709, 122254.0, 122876.38561807312, 123365.0, 102116.0, 106780.0, 107583.0, 108081.91918080876, 109872.0, 11189.328640281608, 11212.439378030902, 11297.604583839526, 11423.543058277908, 11576.94211528937, 35145.29709405747, 44078.89802754957, 44568.0, 45398.09203815971, 45916.622501325015, 61566.69216653888, 62015.37984620276, 62094.68952655208, 62285.0, 62768.430789228805, 14243.928780356031, 14440.0, 14460.27698615086, 14707.632309192355, 14708.338124784625, 15673.294701738678, 15708.293126809549, 16290.755638665192, 16336.264868080727, 16443.835561644708, 14978.550643480714, 15069.835334579422, 15206.543803685907, 15293.694126117638, 15348.693026139637, 14477.565673029825, 14675.192864392135, 15077.170755608548, 15172.544823655307, 15193.164375958986, 12577.93711031439, 14853.18307493055, 14853.554393368217, 14914.328855201758, 15154.545363639108, 9081.727548173565, 10474.162067034467, 10531.631392901212, 10607.363558186533, 10907.509162087883, 11004.889951100387, 11770.470328835394, 11778.528858845893, 11852.051835853283, 12096.334701591144], "value": 8429.055319296818, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 140.0, 168.0, 155.0, 114.0, 115.0, 114.0, 114.0, 112.0, 113.0], "value": 124.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 111.0, 111.0, 140.0, 101.0, 110.0, 108.0, 113.0, 138.0, 112.0], "value": 117.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 201.0, 206.0, 222.0, 208.0, 227.0, 195.0, 196.0, 203.0, 236.0], "value": 209.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 52.0, 57.0, 91.0, 88.0, 85.0, 84.0, 57.0, 79.0, 56.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 68.0, 85.0, 72.0, 70.0, 70.0, 87.0, 71.0, 89.0, 125.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 91.0, 90.0, 90.0, 91.0, 90.0, 91.0, 90.0, 91.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 97.0, 98.0, 98.0, 96.0, 106.0, 104.0, 99.0], "value": 99.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 90.0, 92.0, 118.0, 139.0, 98.0, 145.0, 140.0, 89.0], "value": 109.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 47.0, 67.0, 76.0, 60.0, 49.0, 52.0, 51.0, 50.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 65.0, 52.0, 65.0, 97.0, 89.0, 57.0, 46.0, 74.0, 81.0], "value": 68.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 99.0, 111.0, 118.0, 77.0, 111.0, 116.0, 78.0, 122.0, 78.0], "value": 99.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 165.0, 154.0, 135.0, 139.0, 142.0, 128.0, 127.0, 144.0, 160.0], "value": 143.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 192.0, 191.0, 191.0, 186.0, 188.0, 187.0, 183.0, 188.0, 191.0], "value": 189.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 67.0, 70.0, 68.0, 63.0, 85.0, 77.0, 68.0], "value": 69.8, "unit": "score"}]}]} 19:25:09 INFO - Return code: 0 19:25:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:25:09 INFO - # TBPL SUCCESS # 19:25:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:25:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:25:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:25:09 INFO - 2017-06-01 19:25:09.152 screenresolution[1668:11028] starting screenresolution argv=screenresolution get 19:25:09 INFO - 2017-06-01 19:25:09.154 screenresolution[1668:11028] Display 0: 1600x1200x32@60 19:25:09 INFO - 2017-06-01 19:25:09.160 screenresolution[1669:11031] starting screenresolution argv=screenresolution list 19:25:09 INFO - Available Modes on Display 0 19:25:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:25:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:25:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:25:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:25:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:25:09 INFO - Intel Iris: 19:25:09 INFO - Chipset Model: Intel Iris 19:25:09 INFO - Type: GPU 19:25:09 INFO - Bus: Built-In 19:25:09 INFO - VRAM (Dynamic, Max): 1536 MB 19:25:09 INFO - Vendor: Intel (0x8086) 19:25:09 INFO - Device ID: 0x0a2e 19:25:09 INFO - Revision ID: 0x0009 19:25:09 INFO - Displays: 19:25:09 INFO - Display: 19:25:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:25:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:25:09 INFO - Display Serial Number: 1600x1200 60 19:25:09 INFO - Main Display: Yes 19:25:09 INFO - Mirror: Off 19:25:09 INFO - Online: Yes 19:25:09 INFO - Rotation: Supported 19:25:09 INFO - Return code: 0 19:25:09 INFO - Running post-action listener: _resource_record_post_action 19:25:09 INFO - [mozharness: 2017-06-02 02:25:09.404851Z] Finished run-tests step (success) 19:25:09 INFO - Running post-run listener: _resource_record_post_run 19:25:09 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 47470592; Write bytes: 570340352; Read time: 327; Write time: 1768 19:25:09 INFO - TinderboxPrint: CPU usage
53.1% 19:25:09 INFO - TinderboxPrint: I/O read bytes / time
47,470,592 / 327 19:25:09 INFO - TinderboxPrint: I/O write bytes / time
570,340,352 / 1,768 19:25:09 INFO - TinderboxPrint: CPU idle
2,359.1 (46.9%) 19:25:09 INFO - TinderboxPrint: CPU system
1,012.8 (20.1%) 19:25:09 INFO - TinderboxPrint: CPU user
1,657.8 (33.0%) 19:25:09 INFO - TinderboxPrint: Swap in / out
301,146,112 / 0 19:25:09 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230013440; Write bytes: 231376896; Read time: 16785; Write time: 508 19:25:09 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:25:09 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 46430208; Write bytes: 320506880; Read time: 312; Write time: 1198 19:25:09 INFO - Running post-run listener: _upload_blobber_files 19:25:09 INFO - Blob upload gear active. 19:25:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:25:09 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 19:25:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:25:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:25:10 INFO - (blobuploader) - INFO - Open directory for files ... 19:25:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:25:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:25:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:25:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:25:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:25:11 INFO - (blobuploader) - INFO - Done attempting. 19:25:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:25:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:25:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:25:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:25:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:25:12 INFO - (blobuploader) - INFO - Done attempting. 19:25:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:25:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:25:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:25:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:25:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:25:13 INFO - (blobuploader) - INFO - Done attempting. 19:25:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:25:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:25:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:25:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:25:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:25:15 INFO - (blobuploader) - INFO - Done attempting. 19:25:15 INFO - (blobuploader) - INFO - Iteration through files over. 19:25:15 INFO - Return code: 0 19:25:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:25:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:25:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d1a6ab7fc4960c16c31661bd8881a0a859b276d51ac98f57dcfd93ab607b133849cbdeefc9cd92ca5140487cc3a1b5981c34610d65c06fe32ad5f820bc2fea6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9db1d39878a60a49d7d43feb2a3f82204b1983b730caf869bf6cf9a991699601c4243f0fa8f869c7a146491f234bd9819019be024c81d155d94615a2ae002a42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d82ba5601543ab69bd877ef7a0e6e43c423692c2836ea7dcad284afea4f2d0b6d9d3d0c8f639099b1a1ca91ecbd6b3048303cfac87242f8e75d64f1949978145", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cadbea7f6acf185603505542d66f083d118cadcf00c1ad78eb198d22ebb1a3a93753e0c4d02304a37897d51e0c43fc51546de99f8826ed8709f48ecf3bdeb5e"} 19:25:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:25:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:25:15 INFO - Contents: 19:25:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d1a6ab7fc4960c16c31661bd8881a0a859b276d51ac98f57dcfd93ab607b133849cbdeefc9cd92ca5140487cc3a1b5981c34610d65c06fe32ad5f820bc2fea6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9db1d39878a60a49d7d43feb2a3f82204b1983b730caf869bf6cf9a991699601c4243f0fa8f869c7a146491f234bd9819019be024c81d155d94615a2ae002a42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d82ba5601543ab69bd877ef7a0e6e43c423692c2836ea7dcad284afea4f2d0b6d9d3d0c8f639099b1a1ca91ecbd6b3048303cfac87242f8e75d64f1949978145", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cadbea7f6acf185603505542d66f083d118cadcf00c1ad78eb198d22ebb1a3a93753e0c4d02304a37897d51e0c43fc51546de99f8826ed8709f48ecf3bdeb5e"} 19:25:15 INFO - Running post-run listener: copy_logs_to_upload_dir 19:25:15 INFO - Copying logs to upload dir... 19:25:15 INFO - mkdir: /builds/slave/test/build/upload/logs 19:25:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.462877 ========= master_lag: 0.79 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-01 19:25:16.103499) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 19:25:16.106799) ========= 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.7MedGtEOkO/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.6gObjV8OxF/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d1a6ab7fc4960c16c31661bd8881a0a859b276d51ac98f57dcfd93ab607b133849cbdeefc9cd92ca5140487cc3a1b5981c34610d65c06fe32ad5f820bc2fea6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9db1d39878a60a49d7d43feb2a3f82204b1983b730caf869bf6cf9a991699601c4243f0fa8f869c7a146491f234bd9819019be024c81d155d94615a2ae002a42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d82ba5601543ab69bd877ef7a0e6e43c423692c2836ea7dcad284afea4f2d0b6d9d3d0c8f639099b1a1ca91ecbd6b3048303cfac87242f8e75d64f1949978145", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cadbea7f6acf185603505542d66f083d118cadcf00c1ad78eb198d22ebb1a3a93753e0c4d02304a37897d51e0c43fc51546de99f8826ed8709f48ecf3bdeb5e"} build_url:https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011258 build_url: 'https://queue.taskcluster.net/v1/task/fOzFZKeISn-vVnhJX0jloQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d1a6ab7fc4960c16c31661bd8881a0a859b276d51ac98f57dcfd93ab607b133849cbdeefc9cd92ca5140487cc3a1b5981c34610d65c06fe32ad5f820bc2fea6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9db1d39878a60a49d7d43feb2a3f82204b1983b730caf869bf6cf9a991699601c4243f0fa8f869c7a146491f234bd9819019be024c81d155d94615a2ae002a42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d82ba5601543ab69bd877ef7a0e6e43c423692c2836ea7dcad284afea4f2d0b6d9d3d0c8f639099b1a1ca91ecbd6b3048303cfac87242f8e75d64f1949978145", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cadbea7f6acf185603505542d66f083d118cadcf00c1ad78eb198d22ebb1a3a93753e0c4d02304a37897d51e0c43fc51546de99f8826ed8709f48ecf3bdeb5e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 19:25:16.145523) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 19:25:16.145888) ========= 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.7MedGtEOkO/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.6gObjV8OxF/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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.005059 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 19:25:16.194359) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 19:25:16.194673) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 19:25:16.195001) ========= ========= Total master_lag: 0.93 =========