builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0324 starttime: 1497351631.56 results: success (0) revision: e1ebaa962af13ad2a3d7b4cb74398b83bea7e92e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 04:00:31.560306) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 04:00:31.561263) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 04:00:31.561979) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 04:00:31.586647) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 04:00:31.587022) ========= 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.92i7F8ffKY/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.we4dpWrhyU/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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-13 04:00:31-- 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% 8.42M=0.001s 2017-06-13 04:00:31 (8.42 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.078692 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 04:00:31.688805) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 04:00:31.689216) ========= 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.92i7F8ffKY/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.we4dpWrhyU/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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.024319 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 04:00:31.741666) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-13 04:00:31.742663) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e1ebaa962af13ad2a3d7b4cb74398b83bea7e92e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e1ebaa962af13ad2a3d7b4cb74398b83bea7e92e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.92i7F8ffKY/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.we4dpWrhyU/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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-13 04:00:31,827 truncating revision to first 12 chars 2017-06-13 04:00:31,827 Setting DEBUG logging. 2017-06-13 04:00:31,827 attempt 1/10 2017-06-13 04:00:31,827 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e1ebaa962af1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 04:00:33,371 unpacking tar archive at: mozilla-beta-e1ebaa962af1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.811268 ========= master_lag: 0.81 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-13 04:00:34.360197) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 04:00:34.360647) ========= script_repo_revision: e1ebaa962af13ad2a3d7b4cb74398b83bea7e92e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 04:00:34.361219) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 04:00:34.361654) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 04:00:34.390984) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 7 secs) (at 2017-06-13 04:00:34.391393) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.92i7F8ffKY/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.we4dpWrhyU/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 04:00:34 INFO - MultiFileLogger online at 20170613 04:00:34 in /builds/slave/test 04:00:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 04:00:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:00:34 INFO - {'append_to_log': False, 04:00:34 INFO - 'base_work_dir': '/builds/slave/test', 04:00:34 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:00:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:00:34 INFO - 'branch': 'Mozilla-Beta', 04:00:34 INFO - 'buildbot_json_path': 'buildprops.json', 04:00:34 INFO - 'config_files': ('talos/mac_config.py',), 04:00:34 INFO - 'default_actions': ('clobber', 04:00:34 INFO - 'read-buildbot-config', 04:00:34 INFO - 'download-and-extract', 04:00:34 INFO - 'populate-webroot', 04:00:34 INFO - 'create-virtualenv', 04:00:34 INFO - 'install', 04:00:34 INFO - 'setup-mitmproxy', 04:00:34 INFO - 'run-tests'), 04:00:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:00:34 INFO - 'download_minidump_stackwalk': True, 04:00:34 INFO - 'download_symbols': 'ondemand', 04:00:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:00:34 INFO - 'tooltool.py': '/tools/tooltool.py', 04:00:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:00:34 INFO - '/tools/misc-python/virtualenv.py')}, 04:00:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:00:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:00:34 INFO - 'gecko_profile': False, 04:00:34 INFO - 'gecko_profile_interval': 0, 04:00:34 INFO - 'installer_path': 'installer.exe', 04:00:34 INFO - 'log_level': 'info', 04:00:34 INFO - 'log_name': 'talos', 04:00:34 INFO - 'log_to_console': True, 04:00:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:00:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:00:34 INFO - 'opt_config_files': (), 04:00:34 INFO - 'pip_index': False, 04:00:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:00:34 INFO - 'cmd': ('bash', 04:00:34 INFO - '-c', 04:00:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:00:34 INFO - 'enabled': True, 04:00:34 INFO - 'halt_on_failure': False, 04:00:34 INFO - 'name': 'check_screen_resolution'},), 04:00:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:00:34 INFO - 'cmd': ('bash', 04:00:34 INFO - '-c', 04:00:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:00:34 INFO - 'enabled': True, 04:00:34 INFO - 'halt_on_failure': False, 04:00:34 INFO - 'name': 'check_screen_resolution'},), 04:00:34 INFO - 'run_cmd_checks_enabled': True, 04:00:34 INFO - 'suite': 'dromaeojs-e10s', 04:00:34 INFO - 'system_bits': '32', 04:00:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:00:34 INFO - 'title': 't-yosemite-r7-0324', 04:00:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:00:34 INFO - 'use_talos_json': True, 04:00:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:00:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:00:34 INFO - 'work_dir': 'build'} 04:00:34 INFO - [mozharness: 2017-06-13 11:00:34.593682Z] Running clobber step. 04:00:34 INFO - Running pre-action listener: _resource_record_pre_action 04:00:34 INFO - Running main action method: clobber 04:00:34 INFO - rmtree: /builds/slave/test/build 04:00:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:00:36 INFO - Running post-action listener: _resource_record_post_action 04:00:36 INFO - [mozharness: 2017-06-13 11:00:36.593319Z] Finished clobber step (success) 04:00:36 INFO - [mozharness: 2017-06-13 11:00:36.593547Z] Running read-buildbot-config step. 04:00:36 INFO - Running pre-action listener: _resource_record_pre_action 04:00:36 INFO - Running main action method: read_buildbot_config 04:00:36 INFO - Using buildbot properties: 04:00:36 INFO - { 04:00:36 INFO - "project": "", 04:00:36 INFO - "product": "firefox", 04:00:36 INFO - "who": "cbook@mozilla.com", 04:00:36 INFO - "installer_path": "public/build/firefox-55.0.en-US.mac.dmg", 04:00:36 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 04:00:36 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 04:00:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 04:00:36 INFO - "stage_platform": "macosx64", 04:00:36 INFO - "basedir": "/builds/slave/test", 04:00:36 INFO - "buildnumber": 0, 04:00:36 INFO - "platform": "yosemite_r7", 04:00:36 INFO - "parent_task_id": "DV5DFzMyQjmtJqwKWeJvLg", 04:00:36 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:00:36 INFO - "slavebuilddir": "test", 04:00:36 INFO - "taskId": "Zs9WxFFiS-qrQlMlciyxVQ", 04:00:36 INFO - "branch": "mozilla-beta", 04:00:36 INFO - "script_repo_revision": "production", 04:00:36 INFO - "revision": "e1ebaa962af13ad2a3d7b4cb74398b83bea7e92e", 04:00:36 INFO - "slavename": "t-yosemite-r7-0324", 04:00:36 INFO - "repo_path": "releases/mozilla-beta" 04:00:36 INFO - } 04:00:36 INFO - Finding installer, test and symbols from parent task. 04:00:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zs9WxFFiS-qrQlMlciyxVQ'}, attempt #1 04:00:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zs9WxFFiS-qrQlMlciyxVQ'}, attempt #1 04:00:37 INFO - Task dependencies: PKKd83cvQ12ZjUbuHU8D2w DV5DFzMyQjmtJqwKWeJvLg 04:00:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg'}, attempt #1 04:00:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 04:00:38 INFO - Set test_url: https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/test_packages.json 04:00:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/target.crashreporter-symbols.zip 04:00:38 INFO - Running post-action listener: _resource_record_post_action 04:00:38 INFO - [mozharness: 2017-06-13 11:00:38.524838Z] Finished read-buildbot-config step (success) 04:00:38 INFO - [mozharness: 2017-06-13 11:00:38.524972Z] Running download-and-extract step. 04:00:38 INFO - Running pre-action listener: _resource_record_pre_action 04:00:38 INFO - Running main action method: download_and_extract 04:00:38 INFO - mkdir: /builds/slave/test/build/tests 04:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:38 INFO - trying https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 04:00:38 INFO - Downloading https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 04:00:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 04:00:39 INFO - Downloaded 1290 bytes. 04:00:39 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 04:00:39 INFO - Using the following test package requirements: 04:00:39 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:00:39 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 04:00:39 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 04:00:39 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:00:39 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 04:00:39 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:00:39 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 04:00:39 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:00:39 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:00:39 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 04:00:39 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 04:00:39 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:00:39 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 04:00:39 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:00:39 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 04:00:39 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:00:39 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 04:00:39 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:00:39 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 04:00:39 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 04:00:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 04:00:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 04:00:39 INFO - Fetch https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 04:00:41 INFO - Content-Length response header: 58981178 04:00:41 INFO - Bytes received: 58981178 04:00:59 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 04:00:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 04:00:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 04:00:59 INFO - Fetch https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 04:01:01 INFO - Content-Length response header: 58981178 04:01:01 INFO - Bytes received: 58981178 04:01:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 04:01:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 04:01:18 INFO - Fetch https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 04:01:19 INFO - Content-Length response header: 14450018 04:01:19 INFO - Bytes received: 14450018 04:01:20 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')]} 04:01:20 INFO - trying https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 04:01:20 INFO - Downloading https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 04:01:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 04:01:21 INFO - Downloaded 51077736 bytes. 04:01:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 04:01:21 INFO - mkdir: /builds/slave/test/properties 04:01:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:01:21 INFO - Writing to file /builds/slave/test/properties/build_url 04:01:21 INFO - Contents: 04:01:21 INFO - build_url:https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 04:01:21 INFO - Running post-action listener: _resource_record_post_action 04:01:21 INFO - Running post-action listener: set_extra_try_arguments 04:01:21 INFO - [mozharness: 2017-06-13 11:01:21.754259Z] Finished download-and-extract step (success) 04:01:21 INFO - [mozharness: 2017-06-13 11:01:21.754396Z] Running populate-webroot step. 04:01:21 INFO - Running pre-action listener: _resource_record_pre_action 04:01:21 INFO - Running main action method: populate_webroot 04:01:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:01:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:01:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:01:21 INFO - '--tppagecycles', 04:01:21 INFO - '7'], 04:01:21 INFO - 'tests': ['tsvgm']}}, 04:01:21 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:01:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:01:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:01:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:01:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:01:21 INFO - 'talos_options': ['--disable-e10s'], 04:01:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:01:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:01:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:01:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:01:21 INFO - 'talos_options': ['--disable-e10s'], 04:01:21 INFO - 'tests': ['damp', 'tps']}, 04:01:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:01:21 INFO - 'tests': ['damp', 'tps']}, 04:01:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:01:21 INFO - 'tests': ['dromaeo_dom']}, 04:01:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:01:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:01:21 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:01:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:01:21 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:01:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:01:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:01:21 INFO - 'tests': ['a11yr', 04:01:21 INFO - 'ts_paint', 04:01:21 INFO - 'tpaint', 04:01:21 INFO - 'sessionrestore', 04:01:21 INFO - 'sessionrestore_no_auto_restore', 04:01:21 INFO - 'tabpaint']}, 04:01:21 INFO - 'other-e10s': {'tests': ['a11yr', 04:01:21 INFO - 'ts_paint', 04:01:21 INFO - 'tpaint', 04:01:21 INFO - 'sessionrestore', 04:01:21 INFO - 'sessionrestore_no_auto_restore', 04:01:21 INFO - 'tabpaint']}, 04:01:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:01:21 INFO - 'ts_paint', 04:01:21 INFO - 'tpaint', 04:01:21 INFO - 'sessionrestore', 04:01:21 INFO - 'sessionrestore_no_auto_restore', 04:01:21 INFO - 'tabpaint']}, 04:01:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:01:21 INFO - 'ts_paint', 04:01:21 INFO - 'tpaint', 04:01:21 INFO - 'sessionrestore', 04:01:21 INFO - 'sessionrestore_no_auto_restore', 04:01:21 INFO - 'tabpaint']}, 04:01:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:01:21 INFO - 'tests': ['a11yr', 04:01:21 INFO - 'ts_paint', 04:01:21 INFO - 'tpaint', 04:01:21 INFO - 'sessionrestore', 04:01:21 INFO - 'sessionrestore_no_auto_restore', 04:01:21 INFO - 'tabpaint']}, 04:01:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:01:21 INFO - 'tests': ['a11yr', 04:01:21 INFO - 'ts_paint', 04:01:21 INFO - 'tpaint', 04:01:21 INFO - 'sessionrestore', 04:01:21 INFO - 'sessionrestore_no_auto_restore', 04:01:21 INFO - 'tabpaint']}, 04:01:21 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:01:21 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:01:21 INFO - 'bloom_basic_ref']}, 04:01:21 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:01:21 INFO - 'talos_options': ['--mitmproxy', 04:01:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:01:21 INFO - '--firstNonBlankPaint'], 04:01:21 INFO - 'tests': ['quantum_pageload_google', 04:01:21 INFO - 'quantum_pageload_youtube', 04:01:21 INFO - 'quantum_pageload_amazon', 04:01:21 INFO - 'quantum_pageload_facebook']}, 04:01:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:01:21 INFO - 'tests': ['tsvgx', 04:01:21 INFO - 'tsvgr_opacity', 04:01:21 INFO - 'tart', 04:01:21 INFO - 'tscrollx', 04:01:21 INFO - 'cart', 04:01:21 INFO - 'tsvg_static']}, 04:01:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:01:21 INFO - 'tsvgr_opacity', 04:01:21 INFO - 'tart', 04:01:21 INFO - 'tscrollx', 04:01:21 INFO - 'cart', 04:01:21 INFO - 'tsvg_static']}, 04:01:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:01:21 INFO - 'talos_options': ['--disable-e10s'], 04:01:21 INFO - 'tests': ['tp5o']}, 04:01:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:01:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:01:21 INFO - 'talos_options': ['--disable-e10s', 04:01:21 INFO - '--xperf_path', 04:01:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:01:21 INFO - 'tests': ['tp5n']}, 04:01:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:01:21 INFO - 'talos_options': ['--xperf_path', 04:01:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:01:21 INFO - 'tests': ['tp5n']}}, 04:01:21 INFO - 'talos.zip': {'path': '', 04:01:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:01:21 INFO - Running post-action listener: _resource_record_post_action 04:01:21 INFO - [mozharness: 2017-06-13 11:01:21.776758Z] Finished populate-webroot step (success) 04:01:21 INFO - [mozharness: 2017-06-13 11:01:21.776889Z] Running create-virtualenv step. 04:01:21 INFO - Running pre-action listener: _resource_record_pre_action 04:01:21 INFO - Running main action method: create_virtualenv 04:01:21 INFO - Creating virtualenv /builds/slave/test/build/venv 04:01:21 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:01:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:01:21 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:01:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:01:21 INFO - Using real prefix '/tools/python27' 04:01:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:01:22 INFO - Installing distribute.............................................................................................................................................................................................done. 04:01:25 INFO - Installing pip.................done. 04:01:25 INFO - Return code: 0 04:01:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:01:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:01:25 INFO - Reading from file tmpfile_stdout 04:01:25 INFO - Output received: 04:01:25 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:01:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105676c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e2daf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053ccd30>, '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 0x105396cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bdbe8>, '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.92i7F8ffKY/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.we4dpWrhyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 04:01:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:01:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:01:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.92i7F8ffKY/Render', 04:01:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:25 INFO - 'HOME': '/Users/cltbld', 04:01:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:25 INFO - 'LOGNAME': 'cltbld', 04:01:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:25 INFO - 'MOZ_NO_REMOTE': '1', 04:01:25 INFO - 'NO_EM_RESTART': '1', 04:01:25 INFO - 'PAGER': '/bin/cat', 04:01:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:25 INFO - 'PWD': '/builds/slave/test', 04:01:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:25 INFO - 'SHELL': '/bin/bash', 04:01:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.we4dpWrhyU/Listeners', 04:01:25 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 04:01:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:25 INFO - 'USER': 'cltbld', 04:01:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:25 INFO - 'XPC_FLAGS': '0x0', 04:01:25 INFO - 'XPC_SERVICE_NAME': '0', 04:01:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:26 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 04:01:26 INFO - Cleaning up... 04:01:26 INFO - Return code: 0 04:01:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:01:26 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')]} 04:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:26 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')]} 04:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:26 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 0x105676c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e2daf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053ccd30>, '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 0x105396cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bdbe8>, '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.92i7F8ffKY/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.we4dpWrhyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 04:01:26 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 04:01:26 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 04:01:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.92i7F8ffKY/Render', 04:01:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:26 INFO - 'HOME': '/Users/cltbld', 04:01:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:26 INFO - 'LOGNAME': 'cltbld', 04:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:26 INFO - 'MOZ_NO_REMOTE': '1', 04:01:26 INFO - 'NO_EM_RESTART': '1', 04:01:26 INFO - 'PAGER': '/bin/cat', 04:01:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:26 INFO - 'PWD': '/builds/slave/test', 04:01:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:26 INFO - 'SHELL': '/bin/bash', 04:01:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.we4dpWrhyU/Listeners', 04:01:26 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 04:01:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:26 INFO - 'USER': 'cltbld', 04:01:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:26 INFO - 'XPC_FLAGS': '0x0', 04:01:26 INFO - 'XPC_SERVICE_NAME': '0', 04:01:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:26 INFO - Downloading/unpacking psutil>=3.1.1 04:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:01:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:01:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:01:28 INFO - Installing collected packages: psutil 04:01:28 INFO - Running setup.py install for psutil 04:01:28 INFO - building 'psutil._psutil_osx' extension 04:01:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:01:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:01:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:01:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:01:28 INFO - building 'psutil._psutil_posix' extension 04:01:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:01:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:01:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:01:28 INFO - ^ 04:01:28 INFO - 1 warning generated. 04:01:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:01:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:01:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:01:28 INFO - Successfully installed psutil 04:01:28 INFO - Cleaning up... 04:01:29 INFO - Return code: 0 04:01:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105676c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e2daf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053ccd30>, '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 0x105396cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bdbe8>, '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.92i7F8ffKY/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.we4dpWrhyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 04:01:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:01:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.92i7F8ffKY/Render', 04:01:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:29 INFO - 'HOME': '/Users/cltbld', 04:01:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:29 INFO - 'LOGNAME': 'cltbld', 04:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:29 INFO - 'MOZ_NO_REMOTE': '1', 04:01:29 INFO - 'NO_EM_RESTART': '1', 04:01:29 INFO - 'PAGER': '/bin/cat', 04:01:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:29 INFO - 'PWD': '/builds/slave/test', 04:01:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:29 INFO - 'SHELL': '/bin/bash', 04:01:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.we4dpWrhyU/Listeners', 04:01:29 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 04:01:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:29 INFO - 'USER': 'cltbld', 04:01:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:29 INFO - 'XPC_FLAGS': '0x0', 04:01:29 INFO - 'XPC_SERVICE_NAME': '0', 04:01:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:01:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:01:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:01:31 INFO - Installing collected packages: mozsystemmonitor 04:01:31 INFO - Running setup.py install for mozsystemmonitor 04:01:31 INFO - Successfully installed mozsystemmonitor 04:01:31 INFO - Cleaning up... 04:01:32 INFO - Return code: 0 04:01:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:01:32 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')]} 04:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:32 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')]} 04:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:32 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 0x105676c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e2daf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053ccd30>, '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 0x105396cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bdbe8>, '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.92i7F8ffKY/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.we4dpWrhyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 04:01:32 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 04:01:32 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 04:01:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.92i7F8ffKY/Render', 04:01:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:32 INFO - 'HOME': '/Users/cltbld', 04:01:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:32 INFO - 'LOGNAME': 'cltbld', 04:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:32 INFO - 'MOZ_NO_REMOTE': '1', 04:01:32 INFO - 'NO_EM_RESTART': '1', 04:01:32 INFO - 'PAGER': '/bin/cat', 04:01:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:32 INFO - 'PWD': '/builds/slave/test', 04:01:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:32 INFO - 'SHELL': '/bin/bash', 04:01:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.we4dpWrhyU/Listeners', 04:01:32 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 04:01:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:32 INFO - 'USER': 'cltbld', 04:01:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:32 INFO - 'XPC_FLAGS': '0x0', 04:01:32 INFO - 'XPC_SERVICE_NAME': '0', 04:01:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:32 INFO - Downloading/unpacking jsonschema==2.5.1 04:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:01:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:01:33 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 04:01:33 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 04:01:33 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 04:01:33 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 04:01:33 INFO - Downloading functools32-3.2.3-2.tar.gz 04:01:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:01:33 INFO - warning: no files found matching '*.txt' 04:01:33 INFO - no previously-included directories found matching 'build' 04:01:33 INFO - no previously-included directories found matching 'dist' 04:01:33 INFO - no previously-included directories found matching '.git*' 04:01:33 INFO - Installing collected packages: jsonschema, functools32 04:01:34 INFO - Running setup.py install for functools32 04:01:34 INFO - warning: no files found matching '*.txt' 04:01:34 INFO - no previously-included directories found matching 'build' 04:01:34 INFO - no previously-included directories found matching 'dist' 04:01:34 INFO - no previously-included directories found matching '.git*' 04:01:34 INFO - Successfully installed jsonschema functools32 04:01:34 INFO - Cleaning up... 04:01:34 INFO - Return code: 0 04:01:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105676c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e2daf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053ccd30>, '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 0x105396cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bdbe8>, '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.92i7F8ffKY/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.we4dpWrhyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 04:01:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:01:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:01:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.92i7F8ffKY/Render', 04:01:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:34 INFO - 'HOME': '/Users/cltbld', 04:01:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:34 INFO - 'LOGNAME': 'cltbld', 04:01:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:34 INFO - 'MOZ_NO_REMOTE': '1', 04:01:34 INFO - 'NO_EM_RESTART': '1', 04:01:34 INFO - 'PAGER': '/bin/cat', 04:01:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:34 INFO - 'PWD': '/builds/slave/test', 04:01:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:34 INFO - 'SHELL': '/bin/bash', 04:01:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.we4dpWrhyU/Listeners', 04:01:34 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 04:01:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:34 INFO - 'USER': 'cltbld', 04:01:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:34 INFO - 'XPC_FLAGS': '0x0', 04:01:34 INFO - 'XPC_SERVICE_NAME': '0', 04:01:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:01:34 INFO - Cleaning up... 04:01:34 INFO - Return code: 0 04:01:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105676c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e2daf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053ccd30>, '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 0x105396cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bdbe8>, '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.92i7F8ffKY/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.we4dpWrhyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 04:01:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:01:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:01:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.92i7F8ffKY/Render', 04:01:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:34 INFO - 'HOME': '/Users/cltbld', 04:01:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:34 INFO - 'LOGNAME': 'cltbld', 04:01:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:34 INFO - 'MOZ_NO_REMOTE': '1', 04:01:34 INFO - 'NO_EM_RESTART': '1', 04:01:34 INFO - 'PAGER': '/bin/cat', 04:01:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:34 INFO - 'PWD': '/builds/slave/test', 04:01:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:34 INFO - 'SHELL': '/bin/bash', 04:01:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.we4dpWrhyU/Listeners', 04:01:34 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 04:01:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:34 INFO - 'USER': 'cltbld', 04:01:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:34 INFO - 'XPC_FLAGS': '0x0', 04:01:34 INFO - 'XPC_SERVICE_NAME': '0', 04:01:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:34 INFO - Downloading/unpacking blobuploader==1.2.4 04:01:34 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 04:01:34 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 04:01:34 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 04:01:34 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 04:01:36 INFO - Downloading blobuploader-1.2.4.tar.gz 04:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:01:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:01:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:36 INFO - Downloading docopt-0.6.1.tar.gz 04:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:01:37 INFO - Installing collected packages: blobuploader, requests, docopt 04:01:37 INFO - Running setup.py install for blobuploader 04:01:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:01:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:01:37 INFO - Running setup.py install for requests 04:01:37 INFO - Running setup.py install for docopt 04:01:37 INFO - Successfully installed blobuploader requests docopt 04:01:37 INFO - Cleaning up... 04:01:37 INFO - Return code: 0 04:01:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:01:37 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')]} 04:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:37 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')]} 04:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:37 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 0x105676c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e2daf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053ccd30>, '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 0x105396cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bdbe8>, '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.92i7F8ffKY/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.we4dpWrhyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 04:01:37 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 04:01:37 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 04:01:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.92i7F8ffKY/Render', 04:01:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:37 INFO - 'HOME': '/Users/cltbld', 04:01:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:37 INFO - 'LOGNAME': 'cltbld', 04:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:37 INFO - 'MOZ_NO_REMOTE': '1', 04:01:37 INFO - 'NO_EM_RESTART': '1', 04:01:37 INFO - 'PAGER': '/bin/cat', 04:01:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:37 INFO - 'PWD': '/builds/slave/test', 04:01:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:37 INFO - 'SHELL': '/bin/bash', 04:01:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.we4dpWrhyU/Listeners', 04:01:37 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 04:01:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:37 INFO - 'USER': 'cltbld', 04:01:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:37 INFO - 'XPC_FLAGS': '0x0', 04:01:37 INFO - 'XPC_SERVICE_NAME': '0', 04:01:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:01:38 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-cbjvLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:01:38 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-2Eb0z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:01:38 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-jPoqGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:01:38 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-SpyRqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:01:38 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-NOMDVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:01:38 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-h3KacH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:01:38 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-5wzdTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:01:38 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-JA4rwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:01:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:01:38 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-tyrF8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:01:39 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-g5nIJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:01:39 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-zcoSU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:01:39 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-VKgFF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:01:39 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-lKAot5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:01:39 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-IZegBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:39 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-fVvxNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:01:39 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-UrbHhW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:01:39 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-28srH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:01:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:01:39 INFO - Running setup.py install for manifestparser 04:01:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:01:40 INFO - Running setup.py install for mozcrash 04:01:40 INFO - Running setup.py install for mozdebug 04:01:40 INFO - Running setup.py install for mozdevice 04:01:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:01:40 INFO - Running setup.py install for mozfile 04:01:40 INFO - Running setup.py install for mozhttpd 04:01:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:01:40 INFO - Running setup.py install for mozinfo 04:01:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:01:40 INFO - Running setup.py install for mozInstall 04:01:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:01:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:01:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:01:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:01:41 INFO - Running setup.py install for mozleak 04:01:41 INFO - Running setup.py install for mozlog 04:01:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:01:41 INFO - Running setup.py install for moznetwork 04:01:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:01:41 INFO - Running setup.py install for mozprocess 04:01:41 INFO - Running setup.py install for mozprofile 04:01:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:01:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:01:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:01:41 INFO - Running setup.py install for mozrunner 04:01:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:01:42 INFO - Running setup.py install for mozscreenshot 04:01:42 INFO - Running setup.py install for moztest 04:01:42 INFO - Running setup.py install for mozversion 04:01:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:01:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:01:42 INFO - Cleaning up... 04:01:42 INFO - Return code: 0 04:01:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:01: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')]} 04:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01: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')]} 04:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:42 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 0x105676c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e2daf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053ccd30>, '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 0x105396cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bdbe8>, '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.92i7F8ffKY/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.we4dpWrhyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 04:01:42 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 04:01:42 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 04:01:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.92i7F8ffKY/Render', 04:01:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:42 INFO - 'HOME': '/Users/cltbld', 04:01:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:42 INFO - 'LOGNAME': 'cltbld', 04:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:42 INFO - 'MOZ_NO_REMOTE': '1', 04:01:42 INFO - 'NO_EM_RESTART': '1', 04:01:42 INFO - 'PAGER': '/bin/cat', 04:01:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:42 INFO - 'PWD': '/builds/slave/test', 04:01:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:42 INFO - 'SHELL': '/bin/bash', 04:01:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.we4dpWrhyU/Listeners', 04:01:42 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 04:01:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:42 INFO - 'USER': 'cltbld', 04:01:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:42 INFO - 'XPC_FLAGS': '0x0', 04:01:42 INFO - 'XPC_SERVICE_NAME': '0', 04:01:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:01:42 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-UOC7a0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:01:42 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)) 04:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:01:42 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-uhmTSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:01:43 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-IeU9kM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:01:43 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-ltJ85e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:01:43 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)) 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:01:43 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-UhcNVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:01:43 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)) 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:01:43 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-UQU3Qx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:01:43 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)) 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:01:43 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-jSxiUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:01:43 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-baPNM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:01:43 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-NHMUSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:01:43 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)) 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:01:43 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-8tp7XC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:01:43 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)) 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:01:43 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-y0lq9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:01:43 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)) 04:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:01:43 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-9rg8Gp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:01:44 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-01rLqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:01:44 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-DfTGVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:01:44 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)) 04:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:44 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-NJ4PH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:44 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)) 04:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:01:44 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-EiGihz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:01:44 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)) 04:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:01:44 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-j8CzD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:01:44 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)) 04:01:44 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)) 04:01:44 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)) 04:01:44 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)) 04:01:44 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)) 04:01:44 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)) 04:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:01:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:01:44 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 04:01:44 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 04:01:44 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 04:01:44 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 04:01:46 INFO - Downloading blessings-1.6.tar.gz 04:01:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:01:46 INFO - Installing collected packages: blessings 04:01:46 INFO - Running setup.py install for blessings 04:01:46 INFO - Successfully installed blessings 04:01:46 INFO - Cleaning up... 04:01:46 INFO - Return code: 0 04:01:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:01:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:01:46 INFO - Reading from file tmpfile_stdout 04:01:46 INFO - Current package versions: 04:01:46 INFO - blessings == 1.6 04:01:46 INFO - blobuploader == 1.2.4 04:01:46 INFO - docopt == 0.6.1 04:01:46 INFO - functools32 == 3.2.3-2 04:01:46 INFO - jsonschema == 2.5.1 04:01:46 INFO - manifestparser == 1.1 04:01:46 INFO - mozInstall == 1.13 04:01:46 INFO - mozcrash == 1.0 04:01:46 INFO - mozdebug == 0.1 04:01:46 INFO - mozdevice == 0.51 04:01:46 INFO - mozfile == 1.2 04:01:46 INFO - mozhttpd == 0.7 04:01:46 INFO - mozinfo == 0.10 04:01:46 INFO - mozleak == 0.1 04:01:46 INFO - mozlog == 3.4 04:01:46 INFO - moznetwork == 0.27 04:01:46 INFO - mozprocess == 0.25 04:01:46 INFO - mozprofile == 0.28 04:01:46 INFO - mozrunner == 6.13 04:01:46 INFO - mozscreenshot == 0.1 04:01:46 INFO - mozsystemmonitor == 0.3 04:01:46 INFO - moztest == 0.8 04:01:46 INFO - mozversion == 1.4 04:01:46 INFO - psutil == 3.1.1 04:01:46 INFO - requests == 1.2.3 04:01:46 INFO - wsgiref == 0.1.2 04:01:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:01:46 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')]} 04:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:46 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')]} 04:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:46 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 0x105676c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e2daf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053ccd30>, '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 0x105396cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bdbe8>, '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.92i7F8ffKY/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.we4dpWrhyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 04:01:46 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 04:01:46 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 04:01:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.92i7F8ffKY/Render', 04:01:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:46 INFO - 'HOME': '/Users/cltbld', 04:01:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:46 INFO - 'LOGNAME': 'cltbld', 04:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:46 INFO - 'MOZ_NO_REMOTE': '1', 04:01:46 INFO - 'NO_EM_RESTART': '1', 04:01:46 INFO - 'PAGER': '/bin/cat', 04:01:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:46 INFO - 'PWD': '/builds/slave/test', 04:01:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:46 INFO - 'SHELL': '/bin/bash', 04:01:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.we4dpWrhyU/Listeners', 04:01:46 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 04:01:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:46 INFO - 'USER': 'cltbld', 04:01:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:46 INFO - 'XPC_FLAGS': '0x0', 04:01:46 INFO - 'XPC_SERVICE_NAME': '0', 04:01:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:46 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)) 04:01:46 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)) 04:01:46 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)) 04:01:46 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)) 04:01:46 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)) 04:01:46 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)) 04:01:46 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)) 04:01:46 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)) 04:01:46 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)) 04:01:46 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:01:46 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 04:01:46 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 04:01:46 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 04:01:46 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 04:01:48 INFO - Downloading marionette_driver-1.4.0.tar.gz 04:01:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 04:01:48 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)) 04:01:48 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)) 04:01:48 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)) 04:01:48 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)) 04:01:48 INFO - Installing collected packages: marionette-driver 04:01:48 INFO - Running setup.py install for marionette-driver 04:01:48 INFO - Successfully installed marionette-driver 04:01:48 INFO - Cleaning up... 04:01:48 INFO - Return code: 0 04:01:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:01: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')]} 04:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01: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')]} 04:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105676c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e2daf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053ccd30>, '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 0x105396cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bdbe8>, '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.92i7F8ffKY/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.we4dpWrhyU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 04:01: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', 'jsonschema'] in /builds/slave/test/build 04:01: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 jsonschema 04:01:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.92i7F8ffKY/Render', 04:01:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:48 INFO - 'HOME': '/Users/cltbld', 04:01:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:48 INFO - 'LOGNAME': 'cltbld', 04:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:48 INFO - 'MOZ_NO_REMOTE': '1', 04:01:48 INFO - 'NO_EM_RESTART': '1', 04:01:48 INFO - 'PAGER': '/bin/cat', 04:01:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:48 INFO - 'PWD': '/builds/slave/test', 04:01:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:48 INFO - 'SHELL': '/bin/bash', 04:01:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.we4dpWrhyU/Listeners', 04:01:48 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 04:01:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:48 INFO - 'USER': 'cltbld', 04:01:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:48 INFO - 'XPC_FLAGS': '0x0', 04:01:48 INFO - 'XPC_SERVICE_NAME': '0', 04:01:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:01:49 INFO - Cleaning up... 04:01:49 INFO - Return code: 0 04:01:49 INFO - Running post-action listener: _resource_record_post_action 04:01:49 INFO - Running post-action listener: _start_resource_monitoring 04:01:49 INFO - Starting resource monitoring. 04:01:49 INFO - [mozharness: 2017-06-13 11:01:49.048567Z] Finished create-virtualenv step (success) 04:01:49 INFO - [mozharness: 2017-06-13 11:01:49.049416Z] Running install step. 04:01:49 INFO - Running pre-action listener: _resource_record_pre_action 04:01:49 INFO - Running main action method: install 04:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:01:49 INFO - Reading from file tmpfile_stdout 04:01:49 INFO - Detecting whether we're running mozinstall >=1.0... 04:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:01:49 INFO - Reading from file tmpfile_stdout 04:01:49 INFO - Output received: 04:01:49 INFO - Usage: mozinstall [options] installer 04:01:49 INFO - Options: 04:01:49 INFO - -h, --help show this help message and exit 04:01:49 INFO - -d DEST, --destination=DEST 04:01:49 INFO - Directory to install application into. [default: 04:01:49 INFO - "/builds/slave/test"] 04:01:49 INFO - --app=APP Application being installed. [default: firefox] 04:01:49 INFO - mkdir: /builds/slave/test/build/application 04:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 04:02:05 INFO - Reading from file tmpfile_stdout 04:02:05 INFO - Output received: 04:02:05 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 04:02:05 INFO - Running post-action listener: _resource_record_post_action 04:02:05 INFO - [mozharness: 2017-06-13 11:02:05.537628Z] Finished install step (success) 04:02:05 INFO - [mozharness: 2017-06-13 11:02:05.537776Z] Running setup-mitmproxy step. 04:02:05 INFO - Running pre-action listener: _resource_record_pre_action 04:02:05 INFO - Running main action method: setup_mitmproxy 04:02:05 INFO - Skipping: mitmproxy is not required 04:02:05 INFO - Running post-action listener: _resource_record_post_action 04:02:05 INFO - [mozharness: 2017-06-13 11:02:05.538415Z] Finished setup-mitmproxy step (success) 04:02:05 INFO - [mozharness: 2017-06-13 11:02:05.538506Z] Running run-tests step. 04:02:05 INFO - Running pre-action listener: _resource_record_pre_action 04:02:05 INFO - Running main action method: run_tests 04:02:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:02:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:02:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:02:05 INFO - 2017-06-13 04:02:05.551 screenresolution[3194:100694] starting screenresolution argv=screenresolution get 04:02:05 INFO - 2017-06-13 04:02:05.554 screenresolution[3194:100694] Display 0: 1600x1200x32@60 04:02:05 INFO - 2017-06-13 04:02:05.559 screenresolution[3195:100696] starting screenresolution argv=screenresolution list 04:02:05 INFO - Available Modes on Display 0 04:02:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:02:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:02:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:02:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:02:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:02:05 INFO - Intel Iris: 04:02:05 INFO - Chipset Model: Intel Iris 04:02:05 INFO - Type: GPU 04:02:05 INFO - Bus: Built-In 04:02:05 INFO - VRAM (Dynamic, Max): 1536 MB 04:02:05 INFO - Vendor: Intel (0x8086) 04:02:05 INFO - Device ID: 0x0a2e 04:02:05 INFO - Revision ID: 0x0009 04:02:05 INFO - Displays: 04:02:05 INFO - Display: 04:02:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:02:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:02:05 INFO - Display Serial Number: 1600x1200 60 04:02:05 INFO - Main Display: Yes 04:02:05 INFO - Mirror: Off 04:02:05 INFO - Online: Yes 04:02:05 INFO - Rotation: Supported 04:02:05 INFO - Return code: 0 04:02:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:02:05 INFO - Python 2.7.3 04:02:05 INFO - Return code: 0 04:02:05 INFO - grabbing minidump binary from tooltool 04:02: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')]} 04:02:05 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 0x1053ccd30>, '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 0x105396cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bdbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:02:05 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 04:02:05 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 04:02:05 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 04:02:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:02:05 INFO - Return code: 0 04:02:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:02:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:02:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:02:05 INFO - ENV: RUST_BACKTRACE is now 1 04:02:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:02:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:02:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--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 04:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0324 --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 04:02:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.92i7F8ffKY/Render', 04:02:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:02:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:02:05 INFO - 'HOME': '/Users/cltbld', 04:02:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:02:05 INFO - 'LOGNAME': 'cltbld', 04:02:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:02:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:02:05 INFO - 'MOZ_NO_REMOTE': '1', 04:02:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:02:05 INFO - 'NO_EM_RESTART': '1', 04:02:05 INFO - 'PAGER': '/bin/cat', 04:02:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:02:05 INFO - 'PWD': '/builds/slave/test', 04:02:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:02:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:02:05 INFO - 'RUST_BACKTRACE': '1', 04:02:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:02:05 INFO - 'SHELL': '/bin/bash', 04:02:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.we4dpWrhyU/Listeners', 04:02:05 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 04:02:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:02:05 INFO - 'USER': 'cltbld', 04:02:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:02:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:02:05 INFO - 'XPC_FLAGS': '0x0', 04:02:05 INFO - 'XPC_SERVICE_NAME': '0', 04:02:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:02:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--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 04:02:06 INFO - mozversion application_buildid: 20170613030050 04:02:06 INFO - mozversion application_changeset: e1ebaa962af13ad2a3d7b4cb74398b83bea7e92e 04:02:06 INFO - mozversion application_display_name: Firefox 04:02:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:02:06 INFO - mozversion application_name: Firefox 04:02:06 INFO - mozversion application_remotingname: firefox 04:02:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:02:06 INFO - mozversion application_vendor: Mozilla 04:02:06 INFO - mozversion application_version: 55.0 04:02:06 INFO - mozversion platform_buildid: 20170613030050 04:02:06 INFO - mozversion platform_changeset: e1ebaa962af13ad2a3d7b4cb74398b83bea7e92e 04:02:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:02:06 INFO - mozversion platform_version: 55.0 04:02:06 INFO - using testdate: 1497351726 04:02:06 INFO - actual date: 1497351726 04:02:06 INFO - starting webserver on 'localhost:53175' 04:02:06 INFO - SUITE-START | Running 2 tests 04:02:06 INFO - TEST-START | dromaeo_css 04:02:06 INFO - operating with platform_type : mac_ 04:02:06 INFO - Initialising browser for dromaeo_css test... 04:02:06 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:53175/getInfo.html -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpgY0oDs/profile 04:02:06 INFO - TEST-INFO | started process 3205 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:53175/getInfo.html -foreground) 04:02:38 INFO - TEST-INFO | 3205: killed by SIGTERM 04:02:38 INFO - Browser initialized. 04:02:38 INFO - Running cycle 1/1 for dromaeo_css test... 04:02:38 INFO - TEST-INFO | started process 3210 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpgY0oDs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:02:40 INFO - PID 3210 | 2017-06-13 04:02:40.673 plugin-container[3211:101111] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x954b, name = 'com.apple.tsm.portname' 04:02:40 INFO - PID 3210 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:02:40 INFO - PID 3210 | 2017-06-13 04:02:40.673 plugin-container[3211:101111] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 04:02:40 INFO - PID 3210 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:02:40 INFO - PID 3210 | 2017-06-13 04:02:40.674 plugin-container[3211:101111] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:02:50 INFO - PID 3210 | RSS: Main: 184958976 04:02:50 INFO - PID 3210 | 04:05:53 INFO - PID 3210 | Cycle 1(1): loaded http://localhost:53175/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53175/tests/dromaeo/cssquery-ext.html) 04:05:55 INFO - PID 3210 | RSS: Main: 199028736 04:05:55 INFO - PID 3210 | 04:08:56 INFO - PID 3210 | Cycle 1(1): loaded http://localhost:53175/tests/dromaeo/cssquery-ext.html (next: http://localhost:53175/tests/dromaeo/cssquery-jquery.html) 04:08:57 INFO - PID 3210 | RSS: Main: 208871424 04:08:57 INFO - PID 3210 | 04:11:59 INFO - PID 3210 | Cycle 1(1): loaded http://localhost:53175/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53175/tests/dromaeo/cssquery-mootools.html) 04:11:59 INFO - PID 3210 | RSS: Main: 213643264 04:11:59 INFO - PID 3210 | 04:15:01 INFO - PID 3210 | Cycle 1(1): loaded http://localhost:53175/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53175/tests/dromaeo/cssquery-prototype.html) 04:15:02 INFO - PID 3210 | RSS: Main: 215228416 04:15:02 INFO - PID 3210 | 04:18:04 INFO - PID 3210 | Cycle 1(1): loaded http://localhost:53175/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53175/tests/dromaeo/cssquery-yui.html) 04:18:05 INFO - PID 3210 | RSS: Main: 215801856 04:18:05 INFO - PID 3210 | 04:21:07 INFO - PID 3210 | Cycle 1(1): loaded http://localhost:53175/tests/dromaeo/cssquery-yui.html (next: http://localhost:53175/tests/dromaeo/cssquery-dojo.html) 04:21:07 INFO - PID 3210 | RSS: Main: 214147072 04:21:07 INFO - PID 3210 | 04:21:07 INFO - PID 3210 | __start_tp_report 04:21:07 INFO - PID 3210 | _x_x_mozilla_page_load 04:21:07 INFO - PID 3210 | _x_x_mozilla_page_load_details 04:21:07 INFO - PID 3210 | |i|pagename|runs| 04:21:07 INFO - PID 3210 | |0;dojo.html;3673.5040769496145;3779.697624190065;3781.4138808484695;3825.808709564522;3830.3679892817686;6328.651924144176;6354.364563543643;6375.203099612554;6395.424411802936;6427.42153206212;5378.435264297251;6501.154849869522;6508.674566271691;6524.967375163129;6546.476281897449;5182.611304152194;5271.5782737381105;5713.085906255;5815.069588865761;5900.409959004108;5771.105478650816;5809.912851307234;5914.585978981473;5919.37846526115;5937.198478205431;5585.609007369486;5635.520980716644;5673.943260567383;5790;5804.883902321951;5672.546196304285;5729.799457019026;5803.680797556132;5804;5918.526517878602;5677;5714.857128571777;5745.454181852718;5770.88458230833;5798.304203495565;6416.647084410356;6427.2929977702415;6439.83900402489;6467.932791089464;6485.3514648534765;6821.488388370892;7052.753153639598;7077.221505634376;7108.3602475777425;7108.60902650354;7008;7012.544184627983;7062.788116356466;7179.353858152791;7202.063731714896;6565.507586930999;6578.80263592093;6649.368310010541;6695.832604184934;6707.564008339442;7363.447741419415;7384.1877393486675;7411.295926886937;7412.555246685216;7416.0729908761405;5600.383957764642;6733.562318449285;6742.797716068526;6846.931530684729;6990.266022067712;6992.300769922967;6993.615351155633;6995.965020174968;7078.469114816409;7227.205007449176;6938.236793952661;7047.3657370835845;7054.047703560056;7062.258462861429;7097;7085.574865508086;7090.361867431853;7097.751578694721;7099.432045436352;7129.287071292829;5626;5665.745041473144;5672.4894759471845;5685.715714214272;5712.200291959129;4875.390576177978;4877.926831097537;5019.67372120811;5053.166227572409;5060.772265247999;6427.614343139427;6526.804195874131;6531.575447595891;6533.052707357407;6534.934650653528;7047.119110111236;7126.501144919909;7216.927830721731;7234.023406840114;7248.311410416002;6679.064930909676;6689.76585819494;6719.260881303053;6724.361185687352;6733.764318248838;6229.221347331583;6260.186175797117;6282.371762823681;6289.40250676185;6306.590071645328;6318.462930650946;7405.8889116663295;7411.4070874331;7413.59225242606;7443.925560744323;5884.577638413547;6899.793006209822;6920.653967301715;7158.248383919822;7237.059182306477;7333.193348731528;7380.667869945758;7411.4811963162065;7435.591042492715;7440.813979650552;7100.325469080429;7106.644667766487;7117.430605551647;7130.500864939404;7143.535670181422;7227.891581626385;7250;7254.310840470147;7263.927360726325;7268.27317268269;6717.764878229238;6825.590464572141;6927.44580433554;6984.965075174591;7018.684159212746;6803;6863.176418829772;6975.546589471871;6990.545614535039;7016.789496315118;6737.696803643946;6803.693833777393;6847;6849.041134241111;6890.069840571659;7005.299470052954;7012.158540975116;7028.226895041439;7028.437724981887;7035.261297563887;6569.178852643419;6700.698468569024;6786.76246331376;6830.351116643911;6830.5218634697085 04:21:07 INFO - PID 3210 | |1;ext.html;705.9894101588477;753.773867839648;818.9426740128185;847.7876636409935;871.0288028847837;39654.2069158617;40530.79734601323;40607.98480037997;40670.593294067054;40682;39307.427702891924;39312.410313845314;39616.20767584647;39698.80150599243;39773.60226397735;7803.336716379101;7835.294823465887;7876.330511906494;7891.092524359715;7928.682852685914;8423.915760842405;8606.483610983361;8613.440126391764;8617.267532259766;8637.0499245083;8617;8661.913380866174;8666.220040196382;8695.130486951317;8696.34777391695;8574.356923230782;8604.956975215115;8651.783705407352;8658.220760131555;8668.436551624156;8466.195711407407;8466.899303090559;8499.447535910207;8502.574871256475;8507.65969361231;37233;37294.62705372939;37330.626693733255;37483.062923426856;37502.81248593739;3699.8335074921692;3703.5370578677666;3744.494493243432;3745.2883952049024;3749.193923306475;30867.536986945215;30978.845105774326;30993.69006309953;31011.689883101106;31015;3198.9680103198903;3243.8540265688102;3263.4288999425007;3279.212988882675;3281.1633033576522;252722.99999999817;255749;257097;315774;320857;48892.26661600079;48906;49368.75315623399;49603.50396495989;49660.50339496631;51219;51365.22952155721;51648;51669;51704.74147629238;15828.841711582736;15956.68086638284;16019.958702684287;16032.438864639682;16047.358105675876;11893.881061189277;12155.453004614814;13215.537456188988;13255.602331930058;13264.801027984588;13712.314384280746;13736.901047916142;13859.37632806506;13869;13891.235982021086;4961.231009193581;5820.767169313265;5863.179154918314;5877.617954832922;5949.8215053548465;890.0343127706511;987.0820137272406;1005.4419797012728;1013.2304514721087;1062.0972173652488;23248.186313478946;23772.167974120825;23828.64257036146;23852.16517421882;24693.18894385327;1071.614218881202;1154.417019405195;1174.9588764393204;1177.3053898199933;1185.7628474305172;12061.819072713917;12639.936800316123;12744.426500807485;12767.42546585406;12787.360631968364;319874;319900;322469;322680;323121;55541.444585553625;55864;55878.44121558814;55977;56090.158647620316;1494.775783632442;1547.752359622455;1551.1081128351243;1555.6966391553538;1568.7333153363727;22848.97179626954;23595.76404235936;23624.527509450058;23655.526889462457;24088.277351679477;31930.52104218485;39145;39424.802875986585;39434.605653943094;39617.60382396139;277806;280422;281219;281257;281434.1856581408;33829.985100447026;35178.64821351856;35504.28991420209;35572;35599.82200089087;44023.77988110039;44346;46212.7689361551;46320.305195421424;46408.83977900579;27653.861730692024;29310.413791724473;31114;31798;31937;30409.847950760104;31024.379512410076;31736.88920887758;31849.203769905936;31870;36271.63728362683;36752.26495470022;37039.259214815014;38017.429738553394;38103.047423813514;28434.289142771595;30976.380472390876;30982;32120.678793212704;32272.677273227906;19643.60712785765;22518.437039074153;24440.75559244385;25187.87406062958;25243.87378063098 04:21:07 INFO - PID 3210 | |2;jquery.html;516.3803435876944;523.0950455711619;533.4505459376841;536.8899375627997;548.6954740119234;16165.595860103474;23709;23763.762362376372;24450;24576.75423245767;18434.723479147822;18558.97925614091;22317.218897338596;23197.420064498398;24663.630045549333;6355.459785918202;6502.31725668805;6521.934780652204;6527.1188389567515;6532.836679083037;6345.71444285006;6414.390632889868;6414.743410263583;6435.774747883825;6436.1311222985;6058.182145410357;6278.780242691507;6321.715522801462;6369.968150159243;6390.71241794118;6264.248290205182;6283.277423096334;6330.588511746721;6349;6363.459105975974;5705.515031222351;5741.282339707536;5743.741531631045;5758.74085666146;5773.595848290622;23780.881095594585;28434.857825710948;28704.282392940135;28780.136595901946;29155.70844291551;2249.6288112461425;2301.4131396493954;2350.247920665388;2383.5590415772963;2389.2115601851347;18908;19055.904720476447;19209.51976200606;19218;19220.423387298404;2229.320057382498;2236.932892013242;2250.7636698146785;2259.8757068361233;2283.1894677389455;162146.9999999988;213581;217013;218459;218619.90690046607;15407.306671199598;15694.607634809221;15741.68516629661;15772;15781.210939452982;16653.583660408585;20289.695654565192;20290.985450727403;20292.289769857984;20306.69539956902;10142.340747851365;10272.383656980604;10393.636222732319;10423.843642345371;10439.582416703246;7812.218778122142;8009.799755006172;9741.951290243504;9744.707658770249;9769.560369783234;8632.050474447806;8680.524310867168;8969.775755606031;9000;9026.323025773094;2249.921252756146;2271.216430331533;2436.2204094689882;2659.2022393281936;2668.198391688719;986.5807032011367;993.5494178499328;998.0518507417981;1048.5962904281753;1049.8740151181908;19941.900290498455;20039.59920801576;20495.385138445872;20819.37541873746;20881.164753409998;1028.7068185567077;1039.443897514826;1041.7551875309296;1083.4042172180755;1086.684861390188;9514.476703781358;10466.319689220176;10592.311499752492;10710.839337409945;10904.018638322588;504212;511687;520223;550979.24510378;551109;154879;156391.962361694;156611;157391;158795;1224.9448774805155;1225.6813228560638;1256.8366112405329;1264.8229247905301;1271.7202215512573;16840.73694472944;17926.92438453697;18911.432657020312;18917.297443230174;19423.417297481097;54611;56386.718066409405;67762.66118669375;68244.6587767058;72058;64362.67818661064;90599;92054;95255;95568.52215738877;11525.827112593486;11601.419929003685;11653.184277100521;11673.591424300108;11740.354280514654;13443.193408395528;16131.628811551149;16214.675706485568;16617.83382166163;16713.832861671715;11889.702757431134;14594.92702536523;14824.184669843262;14867.85132148708;15096.471623493126;12769.8084528734;13193.340332983504;13195.67010824737;13284.667883302609;13730.93134534321;12707;14901.25493725331;14912.25438728081;15026.774598380814;15257.160856153017;11530.135239856876;11559.190856639954;11611.593594224161;11625.6512304631;11628.94185529067;12175.452104655134;14456.277186140862;14574.708505830036;14619.634509137357;15015.549533513575 04:21:07 INFO - PID 3210 | |3;mootools.html;1263.4819723913195;1268.2707443220145;1466.9633259168527;1480.2820631993482;1483.866452019318;14128.152310861342;14709.970302078833;14771.335289911953;14774.187419691913;14787.852121478796;14077.084989475678;14589.781153282709;14592.781108283385;14632.048916820399;14660;2900.695426980168;2916.6937471565498;2954.8818047278082;2970.1386597886585;2970.806897551663;2902.506573882445;2912.6067980822636;2917.7082291770757;2918.9854050729823;2926.458605158042;2868.397636496328;2894.8552572371404;2905.607742954706;2918.4017276458276;2919.92700182495;2847.9857600711966;2859.027930503639;2868.928276793076;2883.4809734247933;2888.350121222708;2805.130409573039;2835.248659105339;2840.2047426720346;2842.04791394883;2845.4166895786316;14009.509667161601;14274.714505709931;14280.571582852532;14290.356933937997;14291.642708932255;7796.440711857651;7818.218178182193;8160.183981601852;8186.426950113494;8186.918130818675;4784.856454306377;5012.448630650625;5108.719020453874;5117.5394214520875;5124;1457.5190187238293;1472.3963175098158;1499.9475018374305;1510.5845892379573;1517.0745845034521;19347.806521934883;19707.507312317164;21014;21211.893940530354;21323.68014479784;5063.721495317719;6883.242843287235;7086.18508871476;7098.645067746592;7136.250693677193;14719.92640036793;14957.626059348386;14964.476243331648;14979.753037044531;14988.175650339117;7723.9227607723205;7753.689852405953;7773.41699372538;7846.9607651962515;7885.526868387915;7002.579845209304;7351.485396022227;7376.041114655168;7383.335499805044;7436.553806771611;5408.864778380572;5428.83713488596;5440.319960004999;5484.369297530755;5484.451554844483;1536.6926614677109;1538.3077615073262;1541.383446621338;1551.0693583849825;1554.518099389193;824.1470078468756;835.3442547600105;850.8978922529336;853.8036251662153;870.0472982084607;7093.716251349992;7122.287771222836;7295;7465.216152303929;7481.551106933602;695.8990946312857;710.8009757267973;726.9345758881726;727.0620899040179;727.5452841973766;3815.027168072124;4645.931435769792;4767.404074490689;4800.447948485899;4979.427365852901;17611.295548178186;17655.558611034827;18938.810611893703;19480.70778938317;19593.706094408597;18770.624587508173;19012.334568290044;19223.423297301102;19391.12739926707;19465.51336216606;9837.999999999856;9845.50772461374;9857.802843943224;9874.950625246827;9919.702408927744;7957.403194760301;8284.08875023759;8317.459365141247;8530.616122274389;8696.695615653422;17239.56901077433;17562.209700563762;17634.382796602058;17712.46862594124;17989.73015404796;17028.231238048644;17739.822601773816;18257.81742182561;18287.45137645873;18394.080295985415;11285.21003529778;11408.429578520874;11433.627964644296;11434.313941163557;11474.541018359507;12707.36463176856;12775.808362874379;12865;12873.163244389085;12878.871211287767;10262.538185781508;10328.431936243578;10677.412742298937;10844.295120817122;10926.836097458703;10128.922450895932;10145.239107067147;10145.34055286404;10539.314944528582;10641.308314959502;9072.863907041532;9517.42895426249;9630.855537167088;9639.855402168832;9642.469664168539;6723.899141512783;6810.965945170243;6841.8973715395305;6907;7143.642817858982;6787.394558922209;6847.144106986627;7182.425405967406;7196.136463624399;7282.927170728225 04:21:07 INFO - PID 3210 | |4;prototype.html;415.74016239850084;452.1386758225581;479.1399438008775;493.5508687094747;493.82716049382697;6077;6238.937610623892;6291.276503202133;6386.169797926274;6399.112261882744;5292.259083728281;5374.381946076202;6191.814245572628;6220.6936543325955;6227.034809604507;4716.811327546894;4735.668503204777;4751.909159969619;4757;4763.999120369436;4700.694454860441;4721.291806229059;4724.267738500521;4745.383100196969;4768.523147685239;4680.5085466026085;4691.225947718623;4713.764311784397;4721.197396442613;4729.62163026957;4363.847265345697;4418.917365245536;4524.163029839474;4725.527447255281;4727.976360118195;4148.23257697327;4163.5836416358125;4174.582541745832;4176.498820141603;4180.958190418118;4452.977735111336;6039.516838652898;6061.787837425669;6063.393660633946;6082.9391706082815;4660.883477913046;5559.9444005559835;5564.8052318169075;5588.580856435784;5594.825086731791;6466.572716460284;6670.232388417074;6671.33286671329;6687.799366019027;6704.899426508607;4156.480439945007;4617.191991401492;4741.359916411275;4760.666753327268;4762.14281429346;6134.9386506134815;6933.72265109395;6961.582305061713;7031.718731250795;7069.081019467436;4038.51537815464;4712.787924543404;4847.854564363075;4849.539293767087;4850.181182056729;4712.740799256006;4946.703197808143;4959.876003099879;4960.156773348539;4964.180910149785;5162.483751624807;5586.273784408001;5590.972045139748;5598.692071936082;5606.775728970795;5068.746562671926;5524.475174858382;6566.409023187936;6580;6655.700493477805;1304.7520971015476;1633.3874796951143;1698.8046374667072;1706.906120163378;1707.3682737386998;961.711486554045;1140.3841925360377;1142.9257098288751;1161.280006396023;1166.2535976974743;5204.42751297357;5472.781088756406;6208.534359923024;6323.27282362525;6330;6637.269900310962;6886.380225779704;6918.792436226921;6973.302669732986;7024.789256322319;5629.43705629442;6164.736229073029;6430.485561155097;6439.130717353191;6472.417482426604;3045.6040714706946;3210.807351558914;3625.093726568358;3658.6950221360903;3668.4680721295263;6834.726610935606;7258.927410725931;7275.236100209508;7296.890546641805;7311.817204569822;5976.641401515836;7245.529040612343;7250.3112204340505;7272.96363518179;7321.670524826395;6834.316568343225;7224.241454647292;7224.277572242839;7248.96375518119;7254.093238345207;5763.7982670606325;6974.511784175059;7028.262032486516;7033.648317583998;7036.859262814817;6804.76183333581;6831.28271531482;6858.622775747382;6868.416184624363;6889.931100688929;6017.5557866112695;6733.898991515229;7151.499395042297;7208.27917208275;7293.52592081513;3967.107400834789;4809.903801923872;4843.346148269939;4868.6611181925255;4869.466118172764;6143.143438301211;6288.213973253343;6997.370236678723;7002.75490357835;7029.472789540702;5815.505682017076;6769.323067693388;6777.93222067773;6787.966060169668;6794.286599906938;6700.865982680417;6701.832454188684;6788.219354774066;6790.351762812408;6797.354251346114;6626.966865165836;6637.772012177763;6640.966795166187;6647.235567909559;6647.833804154935;6760.898586521108;6767.593944363354;6769.559978601375;6782.457403407814;6873.628598133636;6519.478441724746;6544.3782840630065;6582.670866456754;6605.438537724347;6627.900581491378 04:21:07 INFO - PID 3210 | |5;yui.html;855.1790281329922;863.8574635185199;870.4994628088849;882.6557642519418;888.147378516624;17790.91104544477;17904;18046.187921543547;18316.542086447844;18368.724469132972;15374.308156132973;15409.537713868574;16669.08320042397;18343.266269349242;19572;1022.5960745505539;1029.0224286927412;1049.2393015064056;1050.4642632257564;1050.5115121468516;879.8240351929608;1037.0148359058887;1049.029647575993;1049.0925349572642;1053.0154305724134;1105.303658695021;1117.8882111788837;1119.6864877834219;1119.9776004479907;1121;962.7785609309827;1103.1450625765096;1103.4813637590319;1104.9226554141253;1105.745678493951;724.257635923183;731.7523622224095;789.1595450844832;803.7950322667741;805.1063319715112;15470.994385365027;18264.90867545667;18292.993885336302;18654.067296635112;18761.249550017983;18362.26550937961;18376.173072211648;18379.816201837944;18497.07514624277;18600.88394696309;15448.536543903701;15556.299966501534;15640.4525841595;15663.530094097081;15687.294071766797;3758.1732018955786;3764.2659681362147;3776.886693399175;3802.258559580883;3813.466114743938;117518;118446;136082;150301;158254.8349033013;13956.860431395557;14134.929325353307;14503.85496145025;14507.564773056825;14517.128972261698;27128.45743085127;27321.86339068292;27631.30921726973;27888.302792430353;27894.581581276296;8557.058723540405;8618.138186181332;8684.869726953975;8687.913120868838;8689.348298877483;4221.071364299849;4272.273713468718;4290.013296941721;4295.033617436115;4297.54875738043;4795.328653988445;4892.046051020017;4947.431045429749;4954.380702412199;4971.801127954914;1058.1217589400778;1082.1288862465633;1084.2627013630806;1087.3584585094832;1095.950682219302;671.6943790575276;683.9658017099125;700.1947760075851;702.0171759536587;716.2837162837163;10966.232363734462;12096.81854772179;12220.388980550937;12493.125481216228;12523.749525009449;663.7146027208347;674.7941877727341;674.8279188806823;695.6799872058909;701.9508634395645;5109.38687357512;5372.623916325898;5642.71786410686;5646.491815736604;5958.106284057426;126686.73313266934;128195;132061.33969330092;136497;137604;103537;103966.48016759868;113200;116439;126323;88292;88851.11148888429;90811.54594226986;101602.80766308091;103438;11890.821637675268;12553.184043037174;12587.74824503523;12627.87372126267;13243.735125297248;44676.77661611801;46590.53409465862;46857.297140542236;47938.5206147948;49478;66634;83409.58295208728;84872.30255394735;90644;93767.06232937584;14430.927845361126;17402.042887641303;17617.911910440365;17985.460436186935;18244.726329104808;15736.92131539335;19738.11178497;19859.801401985795;19919.900400498485;19953.401397958085;17420.216090275713;17612.471625851245;18388.356407526207;18396.724049139542;18695.065246737882;18367.54081147982;18552.907235463736;18574.814251857308;18619.44141675752;18656.906715466335;17359.132043398033;17789.555261118574;17914.820851791315;18304.084795759893;18316.26734930588;11013.83479247828;11266;11970.640880773592;12163.45264463119;12745.681357966125;13760.17438953666;14270.785938210727;14510.419583216553;14843.03520271179;14894.553163405115 04:21:07 INFO - PID 3210 | __end_tp_report 04:21:07 INFO - PID 3210 | __start_cc_report 04:21:07 INFO - PID 3210 | _x_x_mozilla_cycle_collect,387 04:21:07 INFO - PID 3210 | __end_cc_report 04:21:07 INFO - PID 3210 | __startTimestamp1497352867551__endTimestamp 04:21:07 INFO - PID 3210 | 04:21:07 INFO - PID 3210 | ------- Summary: start ------- 04:21:07 INFO - PID 3210 | Number of tests: 6 04:21:07 INFO - PID 3210 | 04:21:07 INFO - PID 3210 | [#0] dojo.html Cycles:165 Average:6516.21 Median:6737.70 stddev:793.22 (11.8%) stddev-sans-first:763.66 04:21:07 INFO - PID 3210 | Values: 3673.5 3779.7 3781.4 3825.8 3830.4 6328.7 6354.4 6375.2 6395.4 6427.4 5378.4 6501.2 6508.7 6525.0 6546.5 5182.6 5271.6 5713.1 5815.1 5900.4 5771.1 5809.9 5914.6 5919.4 5937.2 5585.6 5635.5 5673.9 5790.0 5804.9 5672.5 5729.8 5803.7 5804.0 5918.5 5677.0 5714.9 5745.5 5770.9 5798.3 6416.6 6427.3 6439.8 6467.9 6485.4 6821.5 7052.8 7077.2 7108.4 7108.6 7008.0 7012.5 7062.8 7179.4 7202.1 6565.5 6578.8 6649.4 6695.8 6707.6 7363.4 7384.2 7411.3 7412.6 7416.1 5600.4 6733.6 6742.8 6846.9 6990.3 6992.3 6993.6 6996.0 7078.5 7227.2 6938.2 7047.4 7054.0 7062.3 7097.0 7085.6 7090.4 7097.8 7099.4 7129.3 5626.0 5665.7 5672.5 5685.7 5712.2 4875.4 4877.9 5019.7 5053.2 5060.8 6427.6 6526.8 6531.6 6533.1 6534.9 7047.1 7126.5 7216.9 7234.0 7248.3 6679.1 6689.8 6719.3 6724.4 6733.8 6229.2 6260.2 6282.4 6289.4 6306.6 6318.5 7405.9 7411.4 7413.6 7443.9 5884.6 6899.8 6920.7 7158.2 7237.1 7333.2 7380.7 7411.5 7435.6 7440.8 7100.3 7106.6 7117.4 7130.5 7143.5 7227.9 7250.0 7254.3 7263.9 7268.3 6717.8 6825.6 6927.4 6985.0 7018.7 6803.0 6863.2 6975.5 6990.5 7016.8 6737.7 6803.7 6847.0 6849.0 6890.1 7005.3 7012.2 7028.2 7028.4 7035.3 6569.2 6700.7 6786.8 6830.4 6830.5 04:21:07 INFO - PID 3210 | 04:21:07 INFO - PID 3210 | [#1] ext.html Cycles:180 Average:45036.27 Median:35341.47 stddev:77368.88 (218.9%) stddev-sans-first:77514.33 04:21:07 INFO - PID 3210 | Values: 706.0 753.8 818.9 847.8 871.0 39654.2 40530.8 40608.0 40670.6 40682.0 39307.4 39312.4 39616.2 39698.8 39773.6 7803.3 7835.3 7876.3 7891.1 7928.7 8423.9 8606.5 8613.4 8617.3 8637.0 8617.0 8661.9 8666.2 8695.1 8696.3 8574.4 8605.0 8651.8 8658.2 8668.4 8466.2 8466.9 8499.4 8502.6 8507.7 37233.0 37294.6 37330.6 37483.1 37502.8 3699.8 3703.5 3744.5 3745.3 3749.2 30867.5 30978.8 30993.7 31011.7 31015.0 3199.0 3243.9 3263.4 3279.2 3281.2 252723.0 255749.0 257097.0 315774.0 320857.0 48892.3 48906.0 49368.8 49603.5 49660.5 51219.0 51365.2 51648.0 51669.0 51704.7 15828.8 15956.7 16020.0 16032.4 16047.4 11893.9 12155.5 13215.5 13255.6 13264.8 13712.3 13736.9 13859.4 13869.0 13891.2 4961.2 5820.8 5863.2 5877.6 5949.8 890.0 987.1 1005.4 1013.2 1062.1 23248.2 23772.2 23828.6 23852.2 24693.2 1071.6 1154.4 1175.0 1177.3 1185.8 12061.8 12639.9 12744.4 12767.4 12787.4 319874.0 319900.0 322469.0 322680.0 323121.0 55541.4 55864.0 55878.4 55977.0 56090.2 1494.8 1547.8 1551.1 1555.7 1568.7 22849.0 23595.8 23624.5 23655.5 24088.3 31930.5 39145.0 39424.8 39434.6 39617.6 277806.0 280422.0 281219.0 281257.0 281434.2 33830.0 35178.6 35504.3 35572.0 35599.8 44023.8 44346.0 46212.8 46320.3 46408.8 27653.9 29310.4 31114.0 31798.0 31937.0 30409.8 31024.4 31736.9 31849.2 31870.0 36271.6 36752.3 37039.3 38017.4 38103.0 28434.3 30976.4 30982.0 32120.7 32272.7 19643.6 22518.4 24440.8 25187.9 25243.9 04:21:07 INFO - PID 3210 | 04:21:07 INFO - PID 3210 | [#2] jquery.html Cycles:180 Average:38411.34 Median:215297.00 stddev:93133.96 (43.3%) stddev-sans-first:93351.76 04:21:07 INFO - PID 3210 | Values: 516.4 523.1 533.5 536.9 548.7 16165.6 23709.0 23763.8 24450.0 24576.8 18434.7 18559.0 22317.2 23197.4 24663.6 6355.5 6502.3 6521.9 6527.1 6532.8 6345.7 6414.4 6414.7 6435.8 6436.1 6058.2 6278.8 6321.7 6370.0 6390.7 6264.2 6283.3 6330.6 6349.0 6363.5 5705.5 5741.3 5743.7 5758.7 5773.6 23780.9 28434.9 28704.3 28780.1 29155.7 2249.6 2301.4 2350.2 2383.6 2389.2 18908.0 19055.9 19209.5 19218.0 19220.4 2229.3 2236.9 2250.8 2259.9 2283.2 162147.0 213581.0 217013.0 218459.0 218619.9 15407.3 15694.6 15741.7 15772.0 15781.2 16653.6 20289.7 20291.0 20292.3 20306.7 10142.3 10272.4 10393.6 10423.8 10439.6 7812.2 8009.8 9742.0 9744.7 9769.6 8632.1 8680.5 8969.8 9000.0 9026.3 2249.9 2271.2 2436.2 2659.2 2668.2 986.6 993.5 998.1 1048.6 1049.9 19941.9 20039.6 20495.4 20819.4 20881.2 1028.7 1039.4 1041.8 1083.4 1086.7 9514.5 10466.3 10592.3 10710.8 10904.0 504212.0 511687.0 520223.0 550979.2 551109.0 154879.0 156392.0 156611.0 157391.0 158795.0 1224.9 1225.7 1256.8 1264.8 1271.7 16840.7 17926.9 18911.4 18917.3 19423.4 54611.0 56386.7 67762.7 68244.7 72058.0 64362.7 90599.0 92054.0 95255.0 95568.5 11525.8 11601.4 11653.2 11673.6 11740.4 13443.2 16131.6 16214.7 16617.8 16713.8 11889.7 14594.9 14824.2 14867.9 15096.5 12769.8 13193.3 13195.7 13284.7 13730.9 12707.0 14901.3 14912.3 15026.8 15257.2 11530.1 11559.2 11611.6 11625.7 11628.9 12175.5 14456.3 14574.7 14619.6 15015.5 04:21:07 INFO - PID 3210 | 04:21:07 INFO - PID 3210 | [#3] mootools.html Cycles:180 Average:8657.16 Median:2891.60 stddev:5776.28 (199.8%) stddev-sans-first:5765.76 04:21:07 INFO - PID 3210 | Values: 1263.5 1268.3 1467.0 1480.3 1483.9 14128.2 14710.0 14771.3 14774.2 14787.9 14077.1 14589.8 14592.8 14632.0 14660.0 2900.7 2916.7 2954.9 2970.1 2970.8 2902.5 2912.6 2917.7 2919.0 2926.5 2868.4 2894.9 2905.6 2918.4 2919.9 2848.0 2859.0 2868.9 2883.5 2888.4 2805.1 2835.2 2840.2 2842.0 2845.4 14009.5 14274.7 14280.6 14290.4 14291.6 7796.4 7818.2 8160.2 8186.4 8186.9 4784.9 5012.4 5108.7 5117.5 5124.0 1457.5 1472.4 1499.9 1510.6 1517.1 19347.8 19707.5 21014.0 21211.9 21323.7 5063.7 6883.2 7086.2 7098.6 7136.3 14719.9 14957.6 14964.5 14979.8 14988.2 7723.9 7753.7 7773.4 7847.0 7885.5 7002.6 7351.5 7376.0 7383.3 7436.6 5408.9 5428.8 5440.3 5484.4 5484.5 1536.7 1538.3 1541.4 1551.1 1554.5 824.1 835.3 850.9 853.8 870.0 7093.7 7122.3 7295.0 7465.2 7481.6 695.9 710.8 726.9 727.1 727.5 3815.0 4645.9 4767.4 4800.4 4979.4 17611.3 17655.6 18938.8 19480.7 19593.7 18770.6 19012.3 19223.4 19391.1 19465.5 9838.0 9845.5 9857.8 9875.0 9919.7 7957.4 8284.1 8317.5 8530.6 8696.7 17239.6 17562.2 17634.4 17712.5 17989.7 17028.2 17739.8 18257.8 18287.5 18394.1 11285.2 11408.4 11433.6 11434.3 11474.5 12707.4 12775.8 12865.0 12873.2 12878.9 10262.5 10328.4 10677.4 10844.3 10926.8 10128.9 10145.2 10145.3 10539.3 10641.3 9072.9 9517.4 9630.9 9639.9 9642.5 6723.9 6811.0 6841.9 6907.0 7143.6 6787.4 6847.1 7182.4 7196.1 7282.9 04:21:07 INFO - PID 3210 | 04:21:07 INFO - PID 3210 | [#4] prototype.html Cycles:180 Average:5490.18 Median:6108.94 stddev:1692.17 (27.7%) stddev-sans-first:1653.50 04:21:07 INFO - PID 3210 | Values: 415.7 452.1 479.1 493.6 493.8 6077.0 6238.9 6291.3 6386.2 6399.1 5292.3 5374.4 6191.8 6220.7 6227.0 4716.8 4735.7 4751.9 4757.0 4764.0 4700.7 4721.3 4724.3 4745.4 4768.5 4680.5 4691.2 4713.8 4721.2 4729.6 4363.8 4418.9 4524.2 4725.5 4728.0 4148.2 4163.6 4174.6 4176.5 4181.0 4453.0 6039.5 6061.8 6063.4 6082.9 4660.9 5559.9 5564.8 5588.6 5594.8 6466.6 6670.2 6671.3 6687.8 6704.9 4156.5 4617.2 4741.4 4760.7 4762.1 6134.9 6933.7 6961.6 7031.7 7069.1 4038.5 4712.8 4847.9 4849.5 4850.2 4712.7 4946.7 4959.9 4960.2 4964.2 5162.5 5586.3 5591.0 5598.7 5606.8 5068.7 5524.5 6566.4 6580.0 6655.7 1304.8 1633.4 1698.8 1706.9 1707.4 961.7 1140.4 1142.9 1161.3 1166.3 5204.4 5472.8 6208.5 6323.3 6330.0 6637.3 6886.4 6918.8 6973.3 7024.8 5629.4 6164.7 6430.5 6439.1 6472.4 3045.6 3210.8 3625.1 3658.7 3668.5 6834.7 7258.9 7275.2 7296.9 7311.8 5976.6 7245.5 7250.3 7273.0 7321.7 6834.3 7224.2 7224.3 7249.0 7254.1 5763.8 6974.5 7028.3 7033.6 7036.9 6804.8 6831.3 6858.6 6868.4 6889.9 6017.6 6733.9 7151.5 7208.3 7293.5 3967.1 4809.9 4843.3 4868.7 4869.5 6143.1 6288.2 6997.4 7002.8 7029.5 5815.5 6769.3 6777.9 6788.0 6794.3 6700.9 6701.8 6788.2 6790.4 6797.4 6627.0 6637.8 6641.0 6647.2 6647.8 6760.9 6767.6 6769.6 6782.5 6873.6 6519.5 6544.4 6582.7 6605.4 6627.9 04:21:07 INFO - PID 3210 | 04:21:07 INFO - PID 3210 | [#5] yui.html Cycles:180 Average:25355.39 Median:18310.18 stddev:37083.08 (202.5%) stddev-sans-first:37141.48 04:21:07 INFO - PID 3210 | Values: 855.2 863.9 870.5 882.7 888.1 17790.9 17904.0 18046.2 18316.5 18368.7 15374.3 15409.5 16669.1 18343.3 19572.0 1022.6 1029.0 1049.2 1050.5 1050.5 879.8 1037.0 1049.0 1049.1 1053.0 1105.3 1117.9 1119.7 1120.0 1121.0 962.8 1103.1 1103.5 1104.9 1105.7 724.3 731.8 789.2 803.8 805.1 15471.0 18264.9 18293.0 18654.1 18761.2 18362.3 18376.2 18379.8 18497.1 18600.9 15448.5 15556.3 15640.5 15663.5 15687.3 3758.2 3764.3 3776.9 3802.3 3813.5 117518.0 118446.0 136082.0 150301.0 158254.8 13956.9 14134.9 14503.9 14507.6 14517.1 27128.5 27321.9 27631.3 27888.3 27894.6 8557.1 8618.1 8684.9 8687.9 8689.3 4221.1 4272.3 4290.0 4295.0 4297.5 4795.3 4892.0 4947.4 4954.4 4971.8 1058.1 1082.1 1084.3 1087.4 1096.0 671.7 684.0 700.2 702.0 716.3 10966.2 12096.8 12220.4 12493.1 12523.7 663.7 674.8 674.8 695.7 702.0 5109.4 5372.6 5642.7 5646.5 5958.1 126686.7 128195.0 132061.3 136497.0 137604.0 103537.0 103966.5 113200.0 116439.0 126323.0 88292.0 88851.1 90811.5 101602.8 103438.0 11890.8 12553.2 12587.7 12627.9 13243.7 44676.8 46590.5 46857.3 47938.5 49478.0 66634.0 83409.6 84872.3 90644.0 93767.1 14430.9 17402.0 17617.9 17985.5 18244.7 15736.9 19738.1 19859.8 19919.9 19953.4 17420.2 17612.5 18388.4 18396.7 18695.1 18367.5 18552.9 18574.8 18619.4 18656.9 17359.1 17789.6 17914.8 18304.1 18316.3 11013.8 11266.0 11970.6 12163.5 12745.7 13760.2 14270.8 14510.4 14843.0 14894.6 04:21:07 INFO - PID 3210 | -------- Summary: end -------- 04:21:07 INFO - PID 3210 | 04:21:08 INFO - TEST-INFO | 3210: exit 0 04:21:08 INFO - TEST-OK | dromaeo_css | took 1141870ms 04:21:08 INFO - TEST-START | kraken 04:21:08 INFO - operating with platform_type : mac_ 04:21:08 INFO - Initialising browser for kraken test... 04:21:08 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:53175/getInfo.html -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpWOXMuA/profile 04:21:08 INFO - TEST-INFO | started process 3216 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:53175/getInfo.html -foreground) 04:21:40 INFO - TEST-INFO | 3216: killed by SIGTERM 04:21:40 INFO - Browser initialized. 04:21:40 INFO - Running cycle 1/1 for kraken test... 04:21:40 INFO - TEST-INFO | started process 3219 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpWOXMuA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:21:42 INFO - PID 3219 | 2017-06-13 04:21:42.651 plugin-container[3220:103391] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 04:21:42 INFO - PID 3219 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:21:42 INFO - PID 3219 | 2017-06-13 04:21:42.652 plugin-container[3220:103391] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 04:21:42 INFO - PID 3219 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:21:42 INFO - PID 3219 | 2017-06-13 04:21:42.652 plugin-container[3220:103391] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:21:52 INFO - PID 3219 | RSS: Main: 184623104 04:21:52 INFO - PID 3219 | 04:21:55 INFO - PID 3219 | Cycle 1(1): loaded http://localhost:53175/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53175/tests/kraken/driver.html?testName=audio-beat-detection) 04:21:56 INFO - PID 3219 | RSS: Main: 198344704 04:21:56 INFO - PID 3219 | 04:21:58 INFO - PID 3219 | Cycle 1(1): loaded http://localhost:53175/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53175/tests/kraken/driver.html?testName=audio-dft) 04:21:59 INFO - PID 3219 | RSS: Main: 209817600 04:21:59 INFO - PID 3219 | 04:22:02 INFO - PID 3219 | Cycle 1(1): loaded http://localhost:53175/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53175/tests/kraken/driver.html?testName=audio-fft) 04:22:02 INFO - PID 3219 | RSS: Main: 214159360 04:22:02 INFO - PID 3219 | 04:22:04 INFO - PID 3219 | Cycle 1(1): loaded http://localhost:53175/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53175/tests/kraken/driver.html?testName=audio-oscillator) 04:22:05 INFO - PID 3219 | RSS: Main: 215662592 04:22:05 INFO - PID 3219 | 04:22:07 INFO - PID 3219 | Cycle 1(1): loaded http://localhost:53175/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53175/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:22:08 INFO - PID 3219 | RSS: Main: 215494656 04:22:08 INFO - PID 3219 | 04:22:10 INFO - PID 3219 | Cycle 1(1): loaded http://localhost:53175/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53175/tests/kraken/driver.html?testName=imaging-darkroom) 04:22:11 INFO - PID 3219 | RSS: Main: 219439104 04:22:11 INFO - PID 3219 | 04:22:14 INFO - PID 3219 | Cycle 1(1): loaded http://localhost:53175/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53175/tests/kraken/driver.html?testName=imaging-desaturate) 04:22:15 INFO - PID 3219 | RSS: Main: 220483584 04:22:15 INFO - PID 3219 | 04:22:18 INFO - PID 3219 | Cycle 1(1): loaded http://localhost:53175/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53175/tests/kraken/driver.html?testName=json-parse-financial) 04:22:18 INFO - PID 3219 | RSS: Main: 220827648 04:22:18 INFO - PID 3219 | 04:22:20 INFO - PID 3219 | Cycle 1(1): loaded http://localhost:53175/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53175/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:22:20 INFO - PID 3219 | RSS: Main: 220934144 04:22:20 INFO - PID 3219 | 04:22:22 INFO - PID 3219 | Cycle 1(1): loaded http://localhost:53175/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53175/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:22:23 INFO - PID 3219 | RSS: Main: 220459008 04:22:23 INFO - PID 3219 | 04:22:25 INFO - PID 3219 | Cycle 1(1): loaded http://localhost:53175/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53175/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:22:25 INFO - PID 3219 | RSS: Main: 220372992 04:22:25 INFO - PID 3219 | 04:22:28 INFO - PID 3219 | Cycle 1(1): loaded http://localhost:53175/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53175/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:22:29 INFO - PID 3219 | RSS: Main: 220418048 04:22:29 INFO - PID 3219 | 04:22:32 INFO - PID 3219 | Cycle 1(1): loaded http://localhost:53175/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53175/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:22:32 INFO - PID 3219 | RSS: Main: 220962816 04:22:32 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | Cycle 1(1): loaded http://localhost:53175/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53175/tests/kraken/driver.html?testName=ai-astar) 04:22:34 INFO - PID 3219 | RSS: Main: 222404608 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | __start_tp_report 04:22:34 INFO - PID 3219 | _x_x_mozilla_page_load 04:22:34 INFO - PID 3219 | _x_x_mozilla_page_load_details 04:22:34 INFO - PID 3219 | |i|pagename|runs| 04:22:34 INFO - PID 3219 | |0;ai-astar;100;115;106;112;114;116;116;103;115;113 04:22:34 INFO - PID 3219 | |1;audio-beat-detection;132;104;153;137;107;139;128;109;156;139 04:22:34 INFO - PID 3219 | |2;audio-dft;190;198;204;197;197;192;201;195;200;197 04:22:34 INFO - PID 3219 | |3;audio-fft;64;92;107;171;59;57;55;62;120;55 04:22:34 INFO - PID 3219 | |4;audio-oscillator;68;70;125;68;70;75;106;79;101;154 04:22:34 INFO - PID 3219 | |5;imaging-gaussian-blur;92;91;91;91;91;91;92;92;91;93 04:22:34 INFO - PID 3219 | |6;imaging-darkroom;105;153;98;99;97;98;99;96;96;97 04:22:34 INFO - PID 3219 | |7;imaging-desaturate;91;113;112;91;90;86;92;91;95;90 04:22:34 INFO - PID 3219 | |8;json-parse-financial;49;50;50;54;51;51;51;49;52;52 04:22:34 INFO - PID 3219 | |9;json-stringify-tinderbox;55;48;50;57;61;60;59;56;56;57 04:22:34 INFO - PID 3219 | |10;stanford-crypto-aes;81;77;80;78;80;88;96;86;78;77 04:22:34 INFO - PID 3219 | |11;stanford-crypto-ccm;131;130;127;128;132;137;140;135;163;146 04:22:34 INFO - PID 3219 | |12;stanford-crypto-pbkdf2;224;186;173;172;175;206;219;213;175;178 04:22:34 INFO - PID 3219 | |13;stanford-crypto-sha256-iterative;62;79;64;58;59;92;91;64;65;62 04:22:34 INFO - PID 3219 | __end_tp_report 04:22:34 INFO - PID 3219 | __start_cc_report 04:22:34 INFO - PID 3219 | _x_x_mozilla_cycle_collect,989 04:22:34 INFO - PID 3219 | __end_cc_report 04:22:34 INFO - PID 3219 | __startTimestamp1497352954783__endTimestamp 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | ------- Summary: start ------- 04:22:34 INFO - PID 3219 | Number of tests: 14 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | [#0] ai-astar Cycles:10 Average:111.00 Median:114.50 stddev:5.83 (5.1%) stddev-sans-first:4.63 04:22:34 INFO - PID 3219 | Values: 100.0 115.0 106.0 112.0 114.0 116.0 116.0 103.0 115.0 113.0 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:138.00 stddev:18.46 (13.4%) stddev-sans-first:19.58 04:22:34 INFO - PID 3219 | Values: 132.0 104.0 153.0 137.0 107.0 139.0 128.0 109.0 156.0 139.0 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | [#2] audio-dft Cycles:10 Average:197.10 Median:197.50 stddev:4.12 (2.1%) stddev-sans-first:3.48 04:22:34 INFO - PID 3219 | Values: 190.0 198.0 204.0 197.0 197.0 192.0 201.0 195.0 200.0 197.0 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | [#3] audio-fft Cycles:10 Average:84.20 Median:58.00 stddev:38.55 (66.5%) stddev-sans-first:40.19 04:22:34 INFO - PID 3219 | Values: 64.0 92.0 107.0 171.0 59.0 57.0 55.0 62.0 120.0 55.0 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:69.00 stddev:29.41 (42.6%) stddev-sans-first:29.93 04:22:34 INFO - PID 3219 | Values: 68.0 70.0 125.0 68.0 70.0 75.0 106.0 79.0 101.0 154.0 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:91.50 stddev:0.71 (0.8%) stddev-sans-first:0.73 04:22:34 INFO - PID 3219 | Values: 92.0 91.0 91.0 91.0 91.0 91.0 92.0 92.0 91.0 93.0 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | [#6] imaging-darkroom Cycles:10 Average:103.80 Median:97.50 stddev:17.48 (17.9%) stddev-sans-first:18.53 04:22:34 INFO - PID 3219 | Values: 105.0 153.0 98.0 99.0 97.0 98.0 99.0 96.0 96.0 97.0 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:91.00 stddev:9.43 (10.4%) stddev-sans-first:9.89 04:22:34 INFO - PID 3219 | Values: 91.0 113.0 112.0 91.0 90.0 86.0 92.0 91.0 95.0 90.0 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:1.52 (3.0%) stddev-sans-first:1.45 04:22:34 INFO - PID 3219 | Values: 49.0 50.0 50.0 54.0 51.0 51.0 51.0 49.0 52.0 52.0 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | [#9] json-stringify-tinderbox Cycles:10 Average:55.90 Median:57.00 stddev:4.12 (7.2%) stddev-sans-first:4.36 04:22:34 INFO - PID 3219 | Values: 55.0 48.0 50.0 57.0 61.0 60.0 59.0 56.0 56.0 57.0 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:80.50 stddev:6.14 (7.6%) stddev-sans-first:6.50 04:22:34 INFO - PID 3219 | Values: 81.0 77.0 80.0 78.0 80.0 88.0 96.0 86.0 78.0 77.0 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | [#11] stanford-crypto-ccm Cycles:10 Average:136.90 Median:136.00 stddev:10.86 (8.0%) stddev-sans-first:11.30 04:22:34 INFO - PID 3219 | Values: 131.0 130.0 127.0 128.0 132.0 137.0 140.0 135.0 163.0 146.0 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.10 Median:196.00 stddev:20.98 (10.7%) stddev-sans-first:18.81 04:22:34 INFO - PID 3219 | Values: 224.0 186.0 173.0 172.0 175.0 206.0 219.0 213.0 175.0 178.0 04:22:34 INFO - PID 3219 | 04:22:34 INFO - PID 3219 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:64.50 stddev:12.89 (20.0%) stddev-sans-first:13.37 04:22:34 INFO - PID 3219 | Values: 62.0 79.0 64.0 58.0 59.0 92.0 91.0 64.0 65.0 62.0 04:22:34 INFO - PID 3219 | -------- Summary: end -------- 04:22:34 INFO - PID 3219 | 04:22:35 INFO - TEST-INFO | 3219: exit 0 04:22:35 INFO - TEST-OK | kraken | took 87504ms 04:22:35 INFO - SUITE-END | took 1229s 04:22:36 INFO - Completed test suite (00:20:29) 04:22:36 INFO - javascript benchmark 04:22:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8515.461095266315, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3673.5040769496145, 3779.697624190065, 3781.4138808484695, 3825.808709564522, 3830.3679892817686, 6328.651924144176, 6354.364563543643, 6375.203099612554, 6395.424411802936, 6427.42153206212, 5378.435264297251, 6501.154849869522, 6508.674566271691, 6524.967375163129, 6546.476281897449, 5182.611304152194, 5271.5782737381105, 5713.085906255, 5815.069588865761, 5900.409959004108, 5771.105478650816, 5809.912851307234, 5914.585978981473, 5919.37846526115, 5937.198478205431, 5585.609007369486, 5635.520980716644, 5673.943260567383, 5790.0, 5804.883902321951, 5672.546196304285, 5729.799457019026, 5803.680797556132, 5804.0, 5918.526517878602, 5677.0, 5714.857128571777, 5745.454181852718, 5770.88458230833, 5798.304203495565, 6416.647084410356, 6427.2929977702415, 6439.83900402489, 6467.932791089464, 6485.3514648534765, 6821.488388370892, 7052.753153639598, 7077.221505634376, 7108.3602475777425, 7108.60902650354, 7008.0, 7012.544184627983, 7062.788116356466, 7179.353858152791, 7202.063731714896, 6565.507586930999, 6578.80263592093, 6649.368310010541, 6695.832604184934, 6707.564008339442, 7363.447741419415, 7384.1877393486675, 7411.295926886937, 7412.555246685216, 7416.0729908761405, 5600.383957764642, 6733.562318449285, 6742.797716068526, 6846.931530684729, 6990.266022067712, 6992.300769922967, 6993.615351155633, 6995.965020174968, 7078.469114816409, 7227.205007449176, 6938.236793952661, 7047.3657370835845, 7054.047703560056, 7062.258462861429, 7097.0, 7085.574865508086, 7090.361867431853, 7097.751578694721, 7099.432045436352, 7129.287071292829, 5626.0, 5665.745041473144, 5672.4894759471845, 5685.715714214272, 5712.200291959129, 4875.390576177978, 4877.926831097537, 5019.67372120811, 5053.166227572409, 5060.772265247999, 6427.614343139427, 6526.804195874131, 6531.575447595891, 6533.052707357407, 6534.934650653528, 7047.119110111236, 7126.501144919909, 7216.927830721731, 7234.023406840114, 7248.311410416002, 6679.064930909676, 6689.76585819494, 6719.260881303053, 6724.361185687352, 6733.764318248838, 6229.221347331583, 6260.186175797117, 6282.371762823681, 6289.40250676185, 6306.590071645328, 6318.462930650946, 7405.8889116663295, 7411.4070874331, 7413.59225242606, 7443.925560744323, 5884.577638413547, 6899.793006209822, 6920.653967301715, 7158.248383919822, 7237.059182306477, 7333.193348731528, 7380.667869945758, 7411.4811963162065, 7435.591042492715, 7440.813979650552, 7100.325469080429, 7106.644667766487, 7117.430605551647, 7130.500864939404, 7143.535670181422, 7227.891581626385, 7250.0, 7254.310840470147, 7263.927360726325, 7268.27317268269, 6717.764878229238, 6825.590464572141, 6927.44580433554, 6984.965075174591, 7018.684159212746, 6803.0, 6863.176418829772, 6975.546589471871, 6990.545614535039, 7016.789496315118, 6737.696803643946, 6803.693833777393, 6847.0, 6849.041134241111, 6890.069840571659, 7005.299470052954, 7012.158540975116, 7028.226895041439, 7028.437724981887, 7035.261297563887, 6569.178852643419, 6700.698468569024, 6786.76246331376, 6830.351116643911, 6830.5218634697085], "value": 6462.70263343968, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.9894101588477, 753.773867839648, 818.9426740128185, 847.7876636409935, 871.0288028847837, 39654.2069158617, 40530.79734601323, 40607.98480037997, 40670.593294067054, 40682.0, 39307.427702891924, 39312.410313845314, 39616.20767584647, 39698.80150599243, 39773.60226397735, 7803.336716379101, 7835.294823465887, 7876.330511906494, 7891.092524359715, 7928.682852685914, 8423.915760842405, 8606.483610983361, 8613.440126391764, 8617.267532259766, 8637.0499245083, 8617.0, 8661.913380866174, 8666.220040196382, 8695.130486951317, 8696.34777391695, 8574.356923230782, 8604.956975215115, 8651.783705407352, 8658.220760131555, 8668.436551624156, 8466.195711407407, 8466.899303090559, 8499.447535910207, 8502.574871256475, 8507.65969361231, 37233.0, 37294.62705372939, 37330.626693733255, 37483.062923426856, 37502.81248593739, 3699.8335074921692, 3703.5370578677666, 3744.494493243432, 3745.2883952049024, 3749.193923306475, 30867.536986945215, 30978.845105774326, 30993.69006309953, 31011.689883101106, 31015.0, 3198.9680103198903, 3243.8540265688102, 3263.4288999425007, 3279.212988882675, 3281.1633033576522, 252722.99999999817, 255749.0, 257097.0, 315774.0, 320857.0, 48892.26661600079, 48906.0, 49368.75315623399, 49603.50396495989, 49660.50339496631, 51219.0, 51365.22952155721, 51648.0, 51669.0, 51704.74147629238, 15828.841711582736, 15956.68086638284, 16019.958702684287, 16032.438864639682, 16047.358105675876, 11893.881061189277, 12155.453004614814, 13215.537456188988, 13255.602331930058, 13264.801027984588, 13712.314384280746, 13736.901047916142, 13859.37632806506, 13869.0, 13891.235982021086, 4961.231009193581, 5820.767169313265, 5863.179154918314, 5877.617954832922, 5949.8215053548465, 890.0343127706511, 987.0820137272406, 1005.4419797012728, 1013.2304514721087, 1062.0972173652488, 23248.186313478946, 23772.167974120825, 23828.64257036146, 23852.16517421882, 24693.18894385327, 1071.614218881202, 1154.417019405195, 1174.9588764393204, 1177.3053898199933, 1185.7628474305172, 12061.819072713917, 12639.936800316123, 12744.426500807485, 12767.42546585406, 12787.360631968364, 319874.0, 319900.0, 322469.0, 322680.0, 323121.0, 55541.444585553625, 55864.0, 55878.44121558814, 55977.0, 56090.158647620316, 1494.775783632442, 1547.752359622455, 1551.1081128351243, 1555.6966391553538, 1568.7333153363727, 22848.97179626954, 23595.76404235936, 23624.527509450058, 23655.526889462457, 24088.277351679477, 31930.52104218485, 39145.0, 39424.802875986585, 39434.605653943094, 39617.60382396139, 277806.0, 280422.0, 281219.0, 281257.0, 281434.1856581408, 33829.985100447026, 35178.64821351856, 35504.28991420209, 35572.0, 35599.82200089087, 44023.77988110039, 44346.0, 46212.7689361551, 46320.305195421424, 46408.83977900579, 27653.861730692024, 29310.413791724473, 31114.0, 31798.0, 31937.0, 30409.847950760104, 31024.379512410076, 31736.88920887758, 31849.203769905936, 31870.0, 36271.63728362683, 36752.26495470022, 37039.259214815014, 38017.429738553394, 38103.047423813514, 28434.289142771595, 30976.380472390876, 30982.0, 32120.678793212704, 32272.677273227906, 19643.60712785765, 22518.437039074153, 24440.75559244385, 25187.87406062958, 25243.87378063098], "value": 17711.336077643573, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.3803435876944, 523.0950455711619, 533.4505459376841, 536.8899375627997, 548.6954740119234, 16165.595860103474, 23709.0, 23763.762362376372, 24450.0, 24576.75423245767, 18434.723479147822, 18558.97925614091, 22317.218897338596, 23197.420064498398, 24663.630045549333, 6355.459785918202, 6502.31725668805, 6521.934780652204, 6527.1188389567515, 6532.836679083037, 6345.71444285006, 6414.390632889868, 6414.743410263583, 6435.774747883825, 6436.1311222985, 6058.182145410357, 6278.780242691507, 6321.715522801462, 6369.968150159243, 6390.71241794118, 6264.248290205182, 6283.277423096334, 6330.588511746721, 6349.0, 6363.459105975974, 5705.515031222351, 5741.282339707536, 5743.741531631045, 5758.74085666146, 5773.595848290622, 23780.881095594585, 28434.857825710948, 28704.282392940135, 28780.136595901946, 29155.70844291551, 2249.6288112461425, 2301.4131396493954, 2350.247920665388, 2383.5590415772963, 2389.2115601851347, 18908.0, 19055.904720476447, 19209.51976200606, 19218.0, 19220.423387298404, 2229.320057382498, 2236.932892013242, 2250.7636698146785, 2259.8757068361233, 2283.1894677389455, 162146.9999999988, 213581.0, 217013.0, 218459.0, 218619.90690046607, 15407.306671199598, 15694.607634809221, 15741.68516629661, 15772.0, 15781.210939452982, 16653.583660408585, 20289.695654565192, 20290.985450727403, 20292.289769857984, 20306.69539956902, 10142.340747851365, 10272.383656980604, 10393.636222732319, 10423.843642345371, 10439.582416703246, 7812.218778122142, 8009.799755006172, 9741.951290243504, 9744.707658770249, 9769.560369783234, 8632.050474447806, 8680.524310867168, 8969.775755606031, 9000.0, 9026.323025773094, 2249.921252756146, 2271.216430331533, 2436.2204094689882, 2659.2022393281936, 2668.198391688719, 986.5807032011367, 993.5494178499328, 998.0518507417981, 1048.5962904281753, 1049.8740151181908, 19941.900290498455, 20039.59920801576, 20495.385138445872, 20819.37541873746, 20881.164753409998, 1028.7068185567077, 1039.443897514826, 1041.7551875309296, 1083.4042172180755, 1086.684861390188, 9514.476703781358, 10466.319689220176, 10592.311499752492, 10710.839337409945, 10904.018638322588, 504212.0, 511687.0, 520223.0, 550979.24510378, 551109.0, 154879.0, 156391.962361694, 156611.0, 157391.0, 158795.0, 1224.9448774805155, 1225.6813228560638, 1256.8366112405329, 1264.8229247905301, 1271.7202215512573, 16840.73694472944, 17926.92438453697, 18911.432657020312, 18917.297443230174, 19423.417297481097, 54611.0, 56386.718066409405, 67762.66118669375, 68244.6587767058, 72058.0, 64362.67818661064, 90599.0, 92054.0, 95255.0, 95568.52215738877, 11525.827112593486, 11601.419929003685, 11653.184277100521, 11673.591424300108, 11740.354280514654, 13443.193408395528, 16131.628811551149, 16214.675706485568, 16617.83382166163, 16713.832861671715, 11889.702757431134, 14594.92702536523, 14824.184669843262, 14867.85132148708, 15096.471623493126, 12769.8084528734, 13193.340332983504, 13195.67010824737, 13284.667883302609, 13730.93134534321, 12707.0, 14901.25493725331, 14912.25438728081, 15026.774598380814, 15257.160856153017, 11530.135239856876, 11559.190856639954, 11611.593594224161, 11625.6512304631, 11628.94185529067, 12175.452104655134, 14456.277186140862, 14574.708505830036, 14619.634509137357, 15015.549533513575], "value": 11546.12729406478, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1263.4819723913195, 1268.2707443220145, 1466.9633259168527, 1480.2820631993482, 1483.866452019318, 14128.152310861342, 14709.970302078833, 14771.335289911953, 14774.187419691913, 14787.852121478796, 14077.084989475678, 14589.781153282709, 14592.781108283385, 14632.048916820399, 14660.0, 2900.695426980168, 2916.6937471565498, 2954.8818047278082, 2970.1386597886585, 2970.806897551663, 2902.506573882445, 2912.6067980822636, 2917.7082291770757, 2918.9854050729823, 2926.458605158042, 2868.397636496328, 2894.8552572371404, 2905.607742954706, 2918.4017276458276, 2919.92700182495, 2847.9857600711966, 2859.027930503639, 2868.928276793076, 2883.4809734247933, 2888.350121222708, 2805.130409573039, 2835.248659105339, 2840.2047426720346, 2842.04791394883, 2845.4166895786316, 14009.509667161601, 14274.714505709931, 14280.571582852532, 14290.356933937997, 14291.642708932255, 7796.440711857651, 7818.218178182193, 8160.183981601852, 8186.426950113494, 8186.918130818675, 4784.856454306377, 5012.448630650625, 5108.719020453874, 5117.5394214520875, 5124.0, 1457.5190187238293, 1472.3963175098158, 1499.9475018374305, 1510.5845892379573, 1517.0745845034521, 19347.806521934883, 19707.507312317164, 21014.0, 21211.893940530354, 21323.68014479784, 5063.721495317719, 6883.242843287235, 7086.18508871476, 7098.645067746592, 7136.250693677193, 14719.92640036793, 14957.626059348386, 14964.476243331648, 14979.753037044531, 14988.175650339117, 7723.9227607723205, 7753.689852405953, 7773.41699372538, 7846.9607651962515, 7885.526868387915, 7002.579845209304, 7351.485396022227, 7376.041114655168, 7383.335499805044, 7436.553806771611, 5408.864778380572, 5428.83713488596, 5440.319960004999, 5484.369297530755, 5484.451554844483, 1536.6926614677109, 1538.3077615073262, 1541.383446621338, 1551.0693583849825, 1554.518099389193, 824.1470078468756, 835.3442547600105, 850.8978922529336, 853.8036251662153, 870.0472982084607, 7093.716251349992, 7122.287771222836, 7295.0, 7465.216152303929, 7481.551106933602, 695.8990946312857, 710.8009757267973, 726.9345758881726, 727.0620899040179, 727.5452841973766, 3815.027168072124, 4645.931435769792, 4767.404074490689, 4800.447948485899, 4979.427365852901, 17611.295548178186, 17655.558611034827, 18938.810611893703, 19480.70778938317, 19593.706094408597, 18770.624587508173, 19012.334568290044, 19223.423297301102, 19391.12739926707, 19465.51336216606, 9837.999999999856, 9845.50772461374, 9857.802843943224, 9874.950625246827, 9919.702408927744, 7957.403194760301, 8284.08875023759, 8317.459365141247, 8530.616122274389, 8696.695615653422, 17239.56901077433, 17562.209700563762, 17634.382796602058, 17712.46862594124, 17989.73015404796, 17028.231238048644, 17739.822601773816, 18257.81742182561, 18287.45137645873, 18394.080295985415, 11285.21003529778, 11408.429578520874, 11433.627964644296, 11434.313941163557, 11474.541018359507, 12707.36463176856, 12775.808362874379, 12865.0, 12873.163244389085, 12878.871211287767, 10262.538185781508, 10328.431936243578, 10677.412742298937, 10844.295120817122, 10926.836097458703, 10128.922450895932, 10145.239107067147, 10145.34055286404, 10539.314944528582, 10641.308314959502, 9072.863907041532, 9517.42895426249, 9630.855537167088, 9639.855402168832, 9642.469664168539, 6723.899141512783, 6810.965945170243, 6841.8973715395305, 6907.0, 7143.642817858982, 6787.394558922209, 6847.144106986627, 7182.425405967406, 7196.136463624399, 7282.927170728225], "value": 6329.676450969044, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.74016239850084, 452.1386758225581, 479.1399438008775, 493.5508687094747, 493.82716049382697, 6077.0, 6238.937610623892, 6291.276503202133, 6386.169797926274, 6399.112261882744, 5292.259083728281, 5374.381946076202, 6191.814245572628, 6220.6936543325955, 6227.034809604507, 4716.811327546894, 4735.668503204777, 4751.909159969619, 4757.0, 4763.999120369436, 4700.694454860441, 4721.291806229059, 4724.267738500521, 4745.383100196969, 4768.523147685239, 4680.5085466026085, 4691.225947718623, 4713.764311784397, 4721.197396442613, 4729.62163026957, 4363.847265345697, 4418.917365245536, 4524.163029839474, 4725.527447255281, 4727.976360118195, 4148.23257697327, 4163.5836416358125, 4174.582541745832, 4176.498820141603, 4180.958190418118, 4452.977735111336, 6039.516838652898, 6061.787837425669, 6063.393660633946, 6082.9391706082815, 4660.883477913046, 5559.9444005559835, 5564.8052318169075, 5588.580856435784, 5594.825086731791, 6466.572716460284, 6670.232388417074, 6671.33286671329, 6687.799366019027, 6704.899426508607, 4156.480439945007, 4617.191991401492, 4741.359916411275, 4760.666753327268, 4762.14281429346, 6134.9386506134815, 6933.72265109395, 6961.582305061713, 7031.718731250795, 7069.081019467436, 4038.51537815464, 4712.787924543404, 4847.854564363075, 4849.539293767087, 4850.181182056729, 4712.740799256006, 4946.703197808143, 4959.876003099879, 4960.156773348539, 4964.180910149785, 5162.483751624807, 5586.273784408001, 5590.972045139748, 5598.692071936082, 5606.775728970795, 5068.746562671926, 5524.475174858382, 6566.409023187936, 6580.0, 6655.700493477805, 1304.7520971015476, 1633.3874796951143, 1698.8046374667072, 1706.906120163378, 1707.3682737386998, 961.711486554045, 1140.3841925360377, 1142.9257098288751, 1161.280006396023, 1166.2535976974743, 5204.42751297357, 5472.781088756406, 6208.534359923024, 6323.27282362525, 6330.0, 6637.269900310962, 6886.380225779704, 6918.792436226921, 6973.302669732986, 7024.789256322319, 5629.43705629442, 6164.736229073029, 6430.485561155097, 6439.130717353191, 6472.417482426604, 3045.6040714706946, 3210.807351558914, 3625.093726568358, 3658.6950221360903, 3668.4680721295263, 6834.726610935606, 7258.927410725931, 7275.236100209508, 7296.890546641805, 7311.817204569822, 5976.641401515836, 7245.529040612343, 7250.3112204340505, 7272.96363518179, 7321.670524826395, 6834.316568343225, 7224.241454647292, 7224.277572242839, 7248.96375518119, 7254.093238345207, 5763.7982670606325, 6974.511784175059, 7028.262032486516, 7033.648317583998, 7036.859262814817, 6804.76183333581, 6831.28271531482, 6858.622775747382, 6868.416184624363, 6889.931100688929, 6017.5557866112695, 6733.898991515229, 7151.499395042297, 7208.27917208275, 7293.52592081513, 3967.107400834789, 4809.903801923872, 4843.346148269939, 4868.6611181925255, 4869.466118172764, 6143.143438301211, 6288.213973253343, 6997.370236678723, 7002.75490357835, 7029.472789540702, 5815.505682017076, 6769.323067693388, 6777.93222067773, 6787.966060169668, 6794.286599906938, 6700.865982680417, 6701.832454188684, 6788.219354774066, 6790.351762812408, 6797.354251346114, 6626.966865165836, 6637.772012177763, 6640.966795166187, 6647.235567909559, 6647.833804154935, 6760.898586521108, 6767.593944363354, 6769.559978601375, 6782.457403407814, 6873.628598133636, 6519.478441724746, 6544.3782840630065, 6582.670866456754, 6605.438537724347, 6627.900581491378], "value": 4981.478026849723, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [855.1790281329922, 863.8574635185199, 870.4994628088849, 882.6557642519418, 888.147378516624, 17790.91104544477, 17904.0, 18046.187921543547, 18316.542086447844, 18368.724469132972, 15374.308156132973, 15409.537713868574, 16669.08320042397, 18343.266269349242, 19572.0, 1022.5960745505539, 1029.0224286927412, 1049.2393015064056, 1050.4642632257564, 1050.5115121468516, 879.8240351929608, 1037.0148359058887, 1049.029647575993, 1049.0925349572642, 1053.0154305724134, 1105.303658695021, 1117.8882111788837, 1119.6864877834219, 1119.9776004479907, 1121.0, 962.7785609309827, 1103.1450625765096, 1103.4813637590319, 1104.9226554141253, 1105.745678493951, 724.257635923183, 731.7523622224095, 789.1595450844832, 803.7950322667741, 805.1063319715112, 15470.994385365027, 18264.90867545667, 18292.993885336302, 18654.067296635112, 18761.249550017983, 18362.26550937961, 18376.173072211648, 18379.816201837944, 18497.07514624277, 18600.88394696309, 15448.536543903701, 15556.299966501534, 15640.4525841595, 15663.530094097081, 15687.294071766797, 3758.1732018955786, 3764.2659681362147, 3776.886693399175, 3802.258559580883, 3813.466114743938, 117518.0, 118446.0, 136082.0, 150301.0, 158254.8349033013, 13956.860431395557, 14134.929325353307, 14503.85496145025, 14507.564773056825, 14517.128972261698, 27128.45743085127, 27321.86339068292, 27631.30921726973, 27888.302792430353, 27894.581581276296, 8557.058723540405, 8618.138186181332, 8684.869726953975, 8687.913120868838, 8689.348298877483, 4221.071364299849, 4272.273713468718, 4290.013296941721, 4295.033617436115, 4297.54875738043, 4795.328653988445, 4892.046051020017, 4947.431045429749, 4954.380702412199, 4971.801127954914, 1058.1217589400778, 1082.1288862465633, 1084.2627013630806, 1087.3584585094832, 1095.950682219302, 671.6943790575276, 683.9658017099125, 700.1947760075851, 702.0171759536587, 716.2837162837163, 10966.232363734462, 12096.81854772179, 12220.388980550937, 12493.125481216228, 12523.749525009449, 663.7146027208347, 674.7941877727341, 674.8279188806823, 695.6799872058909, 701.9508634395645, 5109.38687357512, 5372.623916325898, 5642.71786410686, 5646.491815736604, 5958.106284057426, 126686.73313266934, 128195.0, 132061.33969330092, 136497.0, 137604.0, 103537.0, 103966.48016759868, 113200.0, 116439.0, 126323.0, 88292.0, 88851.11148888429, 90811.54594226986, 101602.80766308091, 103438.0, 11890.821637675268, 12553.184043037174, 12587.74824503523, 12627.87372126267, 13243.735125297248, 44676.77661611801, 46590.53409465862, 46857.297140542236, 47938.5206147948, 49478.0, 66634.0, 83409.58295208728, 84872.30255394735, 90644.0, 93767.06232937584, 14430.927845361126, 17402.042887641303, 17617.911910440365, 17985.460436186935, 18244.726329104808, 15736.92131539335, 19738.11178497, 19859.801401985795, 19919.900400498485, 19953.401397958085, 17420.216090275713, 17612.471625851245, 18388.356407526207, 18396.724049139542, 18695.065246737882, 18367.54081147982, 18552.907235463736, 18574.814251857308, 18619.44141675752, 18656.906715466335, 17359.132043398033, 17789.555261118574, 17914.820851791315, 18304.084795759893, 18316.26734930588, 11013.83479247828, 11266.0, 11970.640880773592, 12163.45264463119, 12745.681357966125, 13760.17438953666, 14270.785938210727, 14510.419583216553, 14843.03520271179, 14894.553163405115], "value": 9149.152347849822, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1492.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 115.0, 106.0, 112.0, 114.0, 116.0, 116.0, 103.0, 115.0, 113.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 104.0, 153.0, 137.0, 107.0, 139.0, 128.0, 109.0, 156.0, 139.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 198.0, 204.0, 197.0, 197.0, 192.0, 201.0, 195.0, 200.0, 197.0], "value": 197.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 92.0, 107.0, 171.0, 59.0, 57.0, 55.0, 62.0, 120.0, 55.0], "value": 84.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 125.0, 68.0, 70.0, 75.0, 106.0, 79.0, 101.0, 154.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 91.0, 91.0, 91.0, 91.0, 92.0, 92.0, 91.0, 93.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 153.0, 98.0, 99.0, 97.0, 98.0, 99.0, 96.0, 96.0, 97.0], "value": 103.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 113.0, 112.0, 91.0, 90.0, 86.0, 92.0, 91.0, 95.0, 90.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 50.0, 54.0, 51.0, 51.0, 51.0, 49.0, 52.0, 52.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 48.0, 50.0, 57.0, 61.0, 60.0, 59.0, 56.0, 56.0, 57.0], "value": 55.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 77.0, 80.0, 78.0, 80.0, 88.0, 96.0, 86.0, 78.0, 77.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 130.0, 127.0, 128.0, 132.0, 137.0, 140.0, 135.0, 163.0, 146.0], "value": 136.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 186.0, 173.0, 172.0, 175.0, 206.0, 219.0, 213.0, 175.0, 178.0], "value": 192.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 79.0, 64.0, 58.0, 59.0, 92.0, 91.0, 64.0, 65.0, 62.0], "value": 69.6, "unit": "score"}]}]} 04:22:36 INFO - Return code: 0 04:22:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:22:36 INFO - # TBPL SUCCESS # 04:22:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:22:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:22:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:22:36 INFO - 2017-06-13 04:22:36.157 screenresolution[3226:103775] starting screenresolution argv=screenresolution get 04:22:36 INFO - 2017-06-13 04:22:36.159 screenresolution[3226:103775] Display 0: 1600x1200x32@60 04:22:36 INFO - 2017-06-13 04:22:36.165 screenresolution[3227:103778] starting screenresolution argv=screenresolution list 04:22:36 INFO - Available Modes on Display 0 04:22:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:22:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:22:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:22:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:22:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:22:36 INFO - Intel Iris: 04:22:36 INFO - Chipset Model: Intel Iris 04:22:36 INFO - Type: GPU 04:22:36 INFO - Bus: Built-In 04:22:36 INFO - VRAM (Dynamic, Max): 1536 MB 04:22:36 INFO - Vendor: Intel (0x8086) 04:22:36 INFO - Device ID: 0x0a2e 04:22:36 INFO - Revision ID: 0x0009 04:22:36 INFO - Displays: 04:22:36 INFO - Display: 04:22:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:22:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:22:36 INFO - Display Serial Number: 1600x1200 60 04:22:36 INFO - Main Display: Yes 04:22:36 INFO - Mirror: Off 04:22:36 INFO - Online: Yes 04:22:36 INFO - Rotation: Supported 04:22:36 INFO - Return code: 0 04:22:36 INFO - Running post-action listener: _resource_record_post_action 04:22:36 INFO - [mozharness: 2017-06-13 11:22:36.401342Z] Finished run-tests step (success) 04:22:36 INFO - Running post-run listener: _resource_record_post_run 04:22:36 INFO - Total resource usage - Wall time: 1246s; CPU: 53.0%; Read bytes: 241664; Write bytes: 470595072; Read time: 4; Write time: 1553 04:22:36 INFO - TinderboxPrint: CPU usage
53.0% 04:22:36 INFO - TinderboxPrint: I/O read bytes / time
241,664 / 4 04:22:36 INFO - TinderboxPrint: I/O write bytes / time
470,595,072 / 1,553 04:22:36 INFO - TinderboxPrint: CPU idle
2,342.5 (47.0%) 04:22:36 INFO - TinderboxPrint: CPU system
1,011.3 (20.3%) 04:22:36 INFO - TinderboxPrint: CPU user
1,631.8 (32.7%) 04:22:36 INFO - TinderboxPrint: Swap in / out
153,092,096 / 0 04:22:36 INFO - install - Wall time: 16s; CPU: 55.0%; Read bytes: 144425472; Write bytes: 139646976; Read time: 11565; Write time: 309 04:22:36 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:22:36 INFO - run-tests - Wall time: 1231s; CPU: 53.0%; Read bytes: 241664; Write bytes: 312679936; Read time: 4; Write time: 1193 04:22:36 INFO - Running post-run listener: _upload_blobber_files 04:22:36 INFO - Blob upload gear active. 04:22:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:22:36 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 04:22:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:22:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:22:37 INFO - (blobuploader) - INFO - Open directory for files ... 04:22:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:22:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:22:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:22:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:22:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:22:37 INFO - (blobuploader) - INFO - Done attempting. 04:22:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:22:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:22:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:22:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:22:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:22:38 INFO - (blobuploader) - INFO - Done attempting. 04:22:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:22:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:22:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:22:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:22:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:22:39 INFO - (blobuploader) - INFO - Done attempting. 04:22:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:22:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:22:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:22:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:22:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:22:41 INFO - (blobuploader) - INFO - Done attempting. 04:22:41 INFO - (blobuploader) - INFO - Iteration through files over. 04:22:41 INFO - Return code: 0 04:22:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:22:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:22:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/70485ee2c57f9903279b307117f9f807abbb8eb046f19bed6897f93fab7b3cc6625db9b5f85aaa55e052dad020bd5071654920b6b5154f3ea0b7fbb6fa077565", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed0e3f91966e1a78af77f4dca401aa06b1a9d97caf2f5d25bad3fafa482e858b6bb8f9e18e3475b33f813004accc1c7470762252b74681d5f6087d3b0ab899b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/18cfaf7a2a9660fc5f28fb4bf819d63226f33890d8372e2472d4d3df34a6b5385840aca8d1f92a8e2993d06a66b00a2b2d1238e1b3b05c8cc66b2d12eeaab09d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fc70fa90f244968e57e985fcd6f59067f39af4f7f2fa72235fc498de1ab495482bc7ea6523666d613242c6ed4d1437680009457606bc055b9f92256ed2bfef4d"} 04:22:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:22:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:22:41 INFO - Contents: 04:22:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/70485ee2c57f9903279b307117f9f807abbb8eb046f19bed6897f93fab7b3cc6625db9b5f85aaa55e052dad020bd5071654920b6b5154f3ea0b7fbb6fa077565", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed0e3f91966e1a78af77f4dca401aa06b1a9d97caf2f5d25bad3fafa482e858b6bb8f9e18e3475b33f813004accc1c7470762252b74681d5f6087d3b0ab899b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/18cfaf7a2a9660fc5f28fb4bf819d63226f33890d8372e2472d4d3df34a6b5385840aca8d1f92a8e2993d06a66b00a2b2d1238e1b3b05c8cc66b2d12eeaab09d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fc70fa90f244968e57e985fcd6f59067f39af4f7f2fa72235fc498de1ab495482bc7ea6523666d613242c6ed4d1437680009457606bc055b9f92256ed2bfef4d"} 04:22:41 INFO - Running post-run listener: copy_logs_to_upload_dir 04:22:41 INFO - Copying logs to upload dir... 04:22:41 INFO - mkdir: /builds/slave/test/build/upload/logs 04:22:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1327.556440 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 7 secs) (at 2017-06-13 04:22:41.985408) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 04:22:41.988995) ========= 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.92i7F8ffKY/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.we4dpWrhyU/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/70485ee2c57f9903279b307117f9f807abbb8eb046f19bed6897f93fab7b3cc6625db9b5f85aaa55e052dad020bd5071654920b6b5154f3ea0b7fbb6fa077565", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed0e3f91966e1a78af77f4dca401aa06b1a9d97caf2f5d25bad3fafa482e858b6bb8f9e18e3475b33f813004accc1c7470762252b74681d5f6087d3b0ab899b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/18cfaf7a2a9660fc5f28fb4bf819d63226f33890d8372e2472d4d3df34a6b5385840aca8d1f92a8e2993d06a66b00a2b2d1238e1b3b05c8cc66b2d12eeaab09d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fc70fa90f244968e57e985fcd6f59067f39af4f7f2fa72235fc498de1ab495482bc7ea6523666d613242c6ed4d1437680009457606bc055b9f92256ed2bfef4d"} build_url:https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011338 build_url: 'https://queue.taskcluster.net/v1/task/DV5DFzMyQjmtJqwKWeJvLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/70485ee2c57f9903279b307117f9f807abbb8eb046f19bed6897f93fab7b3cc6625db9b5f85aaa55e052dad020bd5071654920b6b5154f3ea0b7fbb6fa077565", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed0e3f91966e1a78af77f4dca401aa06b1a9d97caf2f5d25bad3fafa482e858b6bb8f9e18e3475b33f813004accc1c7470762252b74681d5f6087d3b0ab899b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/18cfaf7a2a9660fc5f28fb4bf819d63226f33890d8372e2472d4d3df34a6b5385840aca8d1f92a8e2993d06a66b00a2b2d1238e1b3b05c8cc66b2d12eeaab09d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fc70fa90f244968e57e985fcd6f59067f39af4f7f2fa72235fc498de1ab495482bc7ea6523666d613242c6ed4d1437680009457606bc055b9f92256ed2bfef4d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 04:22:42.022251) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 04:22:42.022597) ========= 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.92i7F8ffKY/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.we4dpWrhyU/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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.005297 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 04:22:42.071489) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 04:22:42.071821) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 04:22:42.072124) ========= ========= Total master_lag: 0.96 =========