builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0320 starttime: 1497368060.19 results: success (0) revision: e8dd9736241ea1989feaf3c85a71593c8a6f3bbb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 08:34:20.190063) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 08:34:20.190493) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 08:34:20.190769) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 08:34:20.215862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 08:34:20.216300) ========= 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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 08:34:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.7M=0.001s 2017-06-13 08:34:20 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.337522 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 08:34:20.576747) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 08:34:20.577101) ========= 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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.026940 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 08:34:20.625918) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 08:34:20.626281) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e8dd9736241ea1989feaf3c85a71593c8a6f3bbb --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 e8dd9736241ea1989feaf3c85a71593c8a6f3bbb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 08:34:20,741 truncating revision to first 12 chars 2017-06-13 08:34:20,741 Setting DEBUG logging. 2017-06-13 08:34:20,741 attempt 1/10 2017-06-13 08:34:20,741 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e8dd9736241e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 08:34:22,399 unpacking tar archive at: mozilla-beta-e8dd9736241e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.935812 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 08:34:22.583708) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 08:34:22.584059) ========= script_repo_revision: e8dd9736241ea1989feaf3c85a71593c8a6f3bbb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 08:34:22.584657) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 08:34:22.585306) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 08:34:22.604823) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 9 secs) (at 2017-06-13 08:34:22.605336) ========= /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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 08:34:22 INFO - MultiFileLogger online at 20170613 08:34:22 in /builds/slave/test 08:34:22 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 08:34:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:34:22 INFO - {'append_to_log': False, 08:34:22 INFO - 'base_work_dir': '/builds/slave/test', 08:34:22 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:34:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:34:22 INFO - 'branch': 'Mozilla-Beta', 08:34:22 INFO - 'buildbot_json_path': 'buildprops.json', 08:34:22 INFO - 'config_files': ('talos/mac_config.py',), 08:34:22 INFO - 'default_actions': ('clobber', 08:34:22 INFO - 'read-buildbot-config', 08:34:22 INFO - 'download-and-extract', 08:34:22 INFO - 'populate-webroot', 08:34:22 INFO - 'create-virtualenv', 08:34:22 INFO - 'install', 08:34:22 INFO - 'setup-mitmproxy', 08:34:22 INFO - 'run-tests'), 08:34:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:34:22 INFO - 'download_minidump_stackwalk': True, 08:34:22 INFO - 'download_symbols': 'ondemand', 08:34:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:34:22 INFO - 'tooltool.py': '/tools/tooltool.py', 08:34:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:34:22 INFO - '/tools/misc-python/virtualenv.py')}, 08:34:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:34:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:34:22 INFO - 'gecko_profile': False, 08:34:22 INFO - 'gecko_profile_interval': 0, 08:34:22 INFO - 'installer_path': 'installer.exe', 08:34:22 INFO - 'log_level': 'info', 08:34:22 INFO - 'log_name': 'talos', 08:34:22 INFO - 'log_to_console': True, 08:34:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:34:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:34:22 INFO - 'opt_config_files': (), 08:34:22 INFO - 'pip_index': False, 08:34:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:34:22 INFO - 'cmd': ('bash', 08:34:22 INFO - '-c', 08:34:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:34:22 INFO - 'enabled': True, 08:34:22 INFO - 'halt_on_failure': False, 08:34:22 INFO - 'name': 'check_screen_resolution'},), 08:34:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:34:22 INFO - 'cmd': ('bash', 08:34:22 INFO - '-c', 08:34:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:34:22 INFO - 'enabled': True, 08:34:22 INFO - 'halt_on_failure': False, 08:34:22 INFO - 'name': 'check_screen_resolution'},), 08:34:22 INFO - 'run_cmd_checks_enabled': True, 08:34:22 INFO - 'suite': 'dromaeojs-e10s', 08:34:22 INFO - 'system_bits': '32', 08:34:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:34:22 INFO - 'title': 't-yosemite-r7-0320', 08:34:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:34:22 INFO - 'use_talos_json': True, 08:34:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:34:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:34:22 INFO - 'work_dir': 'build'} 08:34:22 INFO - [mozharness: 2017-06-13 15:34:22.827863Z] Running clobber step. 08:34:22 INFO - Running pre-action listener: _resource_record_pre_action 08:34:22 INFO - Running main action method: clobber 08:34:22 INFO - rmtree: /builds/slave/test/build 08:34:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:34:25 INFO - Running post-action listener: _resource_record_post_action 08:34:25 INFO - [mozharness: 2017-06-13 15:34:25.229494Z] Finished clobber step (success) 08:34:25 INFO - [mozharness: 2017-06-13 15:34:25.229624Z] Running read-buildbot-config step. 08:34:25 INFO - Running pre-action listener: _resource_record_pre_action 08:34:25 INFO - Running main action method: read_buildbot_config 08:34:25 INFO - Using buildbot properties: 08:34:25 INFO - { 08:34:25 INFO - "project": "", 08:34:25 INFO - "product": "firefox", 08:34:25 INFO - "who": "mtabara@mozilla.com", 08:34:25 INFO - "installer_path": "public/build/firefox-55.0.en-US.mac.dmg", 08:34:25 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 08:34:25 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 08:34:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 08:34:25 INFO - "stage_platform": "macosx64", 08:34:25 INFO - "basedir": "/builds/slave/test", 08:34:25 INFO - "buildnumber": 0, 08:34:25 INFO - "platform": "yosemite_r7", 08:34:25 INFO - "parent_task_id": "ezV1EGOcRDeW7fz1QtaKaA", 08:34:25 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:34:25 INFO - "slavebuilddir": "test", 08:34:25 INFO - "taskId": "BKUB_mHTS2W5p1rmlRDkBA", 08:34:25 INFO - "branch": "mozilla-beta", 08:34:25 INFO - "script_repo_revision": "production", 08:34:25 INFO - "revision": "e8dd9736241ea1989feaf3c85a71593c8a6f3bbb", 08:34:25 INFO - "slavename": "t-yosemite-r7-0320", 08:34:25 INFO - "repo_path": "releases/mozilla-beta" 08:34:25 INFO - } 08:34:25 INFO - Finding installer, test and symbols from parent task. 08:34:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BKUB_mHTS2W5p1rmlRDkBA'}, attempt #1 08:34:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BKUB_mHTS2W5p1rmlRDkBA'}, attempt #1 08:34:26 INFO - Task dependencies: WYvG_csBSaSQDWg-iCh5kg ezV1EGOcRDeW7fz1QtaKaA 08:34:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA'}, attempt #1 08:34:27 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 08:34:27 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/test_packages.json 08:34:27 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/target.crashreporter-symbols.zip 08:34:27 INFO - Running post-action listener: _resource_record_post_action 08:34:27 INFO - [mozharness: 2017-06-13 15:34:27.009556Z] Finished read-buildbot-config step (success) 08:34:27 INFO - [mozharness: 2017-06-13 15:34:27.009649Z] Running download-and-extract step. 08:34:27 INFO - Running pre-action listener: _resource_record_pre_action 08:34:27 INFO - Running main action method: download_and_extract 08:34:27 INFO - mkdir: /builds/slave/test/build/tests 08:34:27 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')]} 08:34:27 INFO - trying https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 08:34:27 INFO - Downloading https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/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 08:34:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/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 08:34:28 INFO - Downloaded 1290 bytes. 08:34:28 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 08:34:28 INFO - Using the following test package requirements: 08:34:28 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:34:28 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 08:34:28 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 08:34:28 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:34:28 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 08:34:28 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:34:28 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 08:34:28 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:34:28 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:34:28 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 08:34:28 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 08:34:28 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:34:28 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 08:34:28 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:34:28 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 08:34:28 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:34:28 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 08:34:28 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:34:28 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 08:34:28 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 08:34:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 08:34:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 08:34:28 INFO - Fetch https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 08:34:29 INFO - Content-Length response header: 58982019 08:34:29 INFO - Bytes received: 58982019 08:34:48 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 08:34:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 08:34:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 08:34:48 INFO - Fetch https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 08:34:50 INFO - Content-Length response header: 58982019 08:34:50 INFO - Bytes received: 58982019 08:35:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 08:35:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 08:35:07 INFO - Fetch https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 08:35:08 INFO - Content-Length response header: 14450018 08:35:08 INFO - Bytes received: 14450018 08:35:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:09 INFO - trying https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 08:35:09 INFO - Downloading https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 08:35:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/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 08:35:10 INFO - Downloaded 51078656 bytes. 08:35:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 08:35:10 INFO - mkdir: /builds/slave/test/properties 08:35:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:35:10 INFO - Writing to file /builds/slave/test/properties/build_url 08:35:10 INFO - Contents: 08:35:10 INFO - build_url:https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 08:35:10 INFO - Running post-action listener: _resource_record_post_action 08:35:10 INFO - Running post-action listener: set_extra_try_arguments 08:35:10 INFO - [mozharness: 2017-06-13 15:35:10.662952Z] Finished download-and-extract step (success) 08:35:10 INFO - [mozharness: 2017-06-13 15:35:10.663067Z] Running populate-webroot step. 08:35:10 INFO - Running pre-action listener: _resource_record_pre_action 08:35:10 INFO - Running main action method: populate_webroot 08:35:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:35:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:35:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:35:10 INFO - '--tppagecycles', 08:35:10 INFO - '7'], 08:35:10 INFO - 'tests': ['tsvgm']}}, 08:35:10 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:35:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:35:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:35:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:35:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:35:10 INFO - 'talos_options': ['--disable-e10s'], 08:35:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:35:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:35:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:35:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:35:10 INFO - 'talos_options': ['--disable-e10s'], 08:35:10 INFO - 'tests': ['damp', 'tps']}, 08:35:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:35:10 INFO - 'tests': ['damp', 'tps']}, 08:35:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:35:10 INFO - 'tests': ['dromaeo_dom']}, 08:35:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:35:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:35:10 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:35:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:35:10 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:35:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:35:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:35:10 INFO - 'tests': ['a11yr', 08:35:10 INFO - 'ts_paint', 08:35:10 INFO - 'tpaint', 08:35:10 INFO - 'sessionrestore', 08:35:10 INFO - 'sessionrestore_no_auto_restore', 08:35:10 INFO - 'tabpaint']}, 08:35:10 INFO - 'other-e10s': {'tests': ['a11yr', 08:35:10 INFO - 'ts_paint', 08:35:10 INFO - 'tpaint', 08:35:10 INFO - 'sessionrestore', 08:35:10 INFO - 'sessionrestore_no_auto_restore', 08:35:10 INFO - 'tabpaint']}, 08:35:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:35:10 INFO - 'ts_paint', 08:35:10 INFO - 'tpaint', 08:35:10 INFO - 'sessionrestore', 08:35:10 INFO - 'sessionrestore_no_auto_restore', 08:35:10 INFO - 'tabpaint']}, 08:35:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:35:10 INFO - 'ts_paint', 08:35:10 INFO - 'tpaint', 08:35:10 INFO - 'sessionrestore', 08:35:10 INFO - 'sessionrestore_no_auto_restore', 08:35:10 INFO - 'tabpaint']}, 08:35:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:35:10 INFO - 'tests': ['a11yr', 08:35:10 INFO - 'ts_paint', 08:35:10 INFO - 'tpaint', 08:35:10 INFO - 'sessionrestore', 08:35:10 INFO - 'sessionrestore_no_auto_restore', 08:35:10 INFO - 'tabpaint']}, 08:35:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:35:10 INFO - 'tests': ['a11yr', 08:35:10 INFO - 'ts_paint', 08:35:10 INFO - 'tpaint', 08:35:10 INFO - 'sessionrestore', 08:35:10 INFO - 'sessionrestore_no_auto_restore', 08:35:10 INFO - 'tabpaint']}, 08:35:10 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:35:10 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:35:10 INFO - 'bloom_basic_ref']}, 08:35:10 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:35:10 INFO - 'talos_options': ['--mitmproxy', 08:35:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:35:10 INFO - '--firstNonBlankPaint'], 08:35:10 INFO - 'tests': ['quantum_pageload_google', 08:35:10 INFO - 'quantum_pageload_youtube', 08:35:10 INFO - 'quantum_pageload_amazon', 08:35:10 INFO - 'quantum_pageload_facebook']}, 08:35:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:35:10 INFO - 'tests': ['tsvgx', 08:35:10 INFO - 'tsvgr_opacity', 08:35:10 INFO - 'tart', 08:35:10 INFO - 'tscrollx', 08:35:10 INFO - 'cart', 08:35:10 INFO - 'tsvg_static']}, 08:35:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:35:10 INFO - 'tsvgr_opacity', 08:35:10 INFO - 'tart', 08:35:10 INFO - 'tscrollx', 08:35:10 INFO - 'cart', 08:35:10 INFO - 'tsvg_static']}, 08:35:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:35:10 INFO - 'talos_options': ['--disable-e10s'], 08:35:10 INFO - 'tests': ['tp5o']}, 08:35:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:35:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:35:10 INFO - 'talos_options': ['--disable-e10s', 08:35:10 INFO - '--xperf_path', 08:35:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:35:10 INFO - 'tests': ['tp5n']}, 08:35:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:35:10 INFO - 'talos_options': ['--xperf_path', 08:35:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:35:10 INFO - 'tests': ['tp5n']}}, 08:35:10 INFO - 'talos.zip': {'path': '', 08:35:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:35:10 INFO - Running post-action listener: _resource_record_post_action 08:35:10 INFO - [mozharness: 2017-06-13 15:35:10.686019Z] Finished populate-webroot step (success) 08:35:10 INFO - [mozharness: 2017-06-13 15:35:10.686153Z] Running create-virtualenv step. 08:35:10 INFO - Running pre-action listener: _resource_record_pre_action 08:35:10 INFO - Running main action method: create_virtualenv 08:35:10 INFO - Creating virtualenv /builds/slave/test/build/venv 08:35:10 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 08:35:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:35:10 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:35:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:35:10 INFO - Using real prefix '/tools/python27' 08:35:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:35:11 INFO - Installing distribute.............................................................................................................................................................................................done. 08:35:14 INFO - Installing pip.................done. 08:35:14 INFO - Return code: 0 08:35:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:35:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:35:14 INFO - Reading from file tmpfile_stdout 08:35:14 INFO - Output received: 08:35:14 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) 08:35:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:14 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 0x10e9adc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda1d8e430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e703d30>, '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 0x10e6cdcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6f4be8>, '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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 08:35:14 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 08:35:14 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 08:35:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hpq3qqdIu7/Render', 08:35:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:14 INFO - 'HOME': '/Users/cltbld', 08:35:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:14 INFO - 'LOGNAME': 'cltbld', 08:35:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:14 INFO - 'MOZ_NO_REMOTE': '1', 08:35:14 INFO - 'NO_EM_RESTART': '1', 08:35:14 INFO - 'PAGER': '/bin/cat', 08:35:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:14 INFO - 'PWD': '/builds/slave/test', 08:35:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:14 INFO - 'SHELL': '/bin/bash', 08:35:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ndLLBMP4SO/Listeners', 08:35:14 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 08:35:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:14 INFO - 'USER': 'cltbld', 08:35:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:14 INFO - 'XPC_FLAGS': '0x0', 08:35:14 INFO - 'XPC_SERVICE_NAME': '0', 08:35:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:14 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 08:35:14 INFO - Cleaning up... 08:35:14 INFO - Return code: 0 08:35:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:14 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 0x10e9adc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda1d8e430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e703d30>, '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 0x10e6cdcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6f4be8>, '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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 08:35:14 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 08:35:14 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 08:35:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hpq3qqdIu7/Render', 08:35:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:14 INFO - 'HOME': '/Users/cltbld', 08:35:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:14 INFO - 'LOGNAME': 'cltbld', 08:35:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:14 INFO - 'MOZ_NO_REMOTE': '1', 08:35:14 INFO - 'NO_EM_RESTART': '1', 08:35:14 INFO - 'PAGER': '/bin/cat', 08:35:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:14 INFO - 'PWD': '/builds/slave/test', 08:35:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:14 INFO - 'SHELL': '/bin/bash', 08:35:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ndLLBMP4SO/Listeners', 08:35:14 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 08:35:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:14 INFO - 'USER': 'cltbld', 08:35:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:14 INFO - 'XPC_FLAGS': '0x0', 08:35:14 INFO - 'XPC_SERVICE_NAME': '0', 08:35:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:15 INFO - Downloading/unpacking psutil>=3.1.1 08:35:15 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 08:35:15 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 08:35:15 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 08:35:15 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 08:35:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:35:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:35:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:35:17 INFO - Installing collected packages: psutil 08:35:17 INFO - Running setup.py install for psutil 08:35:17 INFO - building 'psutil._psutil_osx' extension 08:35:17 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 08:35:17 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 08:35:17 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 08:35:17 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 08:35:17 INFO - building 'psutil._psutil_posix' extension 08:35:17 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 08:35:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:35:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:35:18 INFO - ^ 08:35:18 INFO - 1 warning generated. 08:35:18 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 08:35:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:35:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:35:18 INFO - Successfully installed psutil 08:35:18 INFO - Cleaning up... 08:35:18 INFO - Return code: 0 08:35:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:35:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:19 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 0x10e9adc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda1d8e430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e703d30>, '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 0x10e6cdcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6f4be8>, '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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 08:35:19 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 08:35:19 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 08:35:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hpq3qqdIu7/Render', 08:35:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:19 INFO - 'HOME': '/Users/cltbld', 08:35:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:19 INFO - 'LOGNAME': 'cltbld', 08:35:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:19 INFO - 'MOZ_NO_REMOTE': '1', 08:35:19 INFO - 'NO_EM_RESTART': '1', 08:35:19 INFO - 'PAGER': '/bin/cat', 08:35:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:19 INFO - 'PWD': '/builds/slave/test', 08:35:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:19 INFO - 'SHELL': '/bin/bash', 08:35:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ndLLBMP4SO/Listeners', 08:35:19 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 08:35:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:19 INFO - 'USER': 'cltbld', 08:35:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:19 INFO - 'XPC_FLAGS': '0x0', 08:35:19 INFO - 'XPC_SERVICE_NAME': '0', 08:35:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:35:19 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 08:35:19 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 08:35:19 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 08:35:19 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 08:35:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:35:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:35:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:35:21 INFO - Installing collected packages: mozsystemmonitor 08:35:21 INFO - Running setup.py install for mozsystemmonitor 08:35:21 INFO - Successfully installed mozsystemmonitor 08:35:21 INFO - Cleaning up... 08:35:21 INFO - Return code: 0 08:35:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:35:21 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')]} 08:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:21 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')]} 08:35:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:21 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 0x10e9adc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda1d8e430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e703d30>, '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 0x10e6cdcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6f4be8>, '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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 08:35:21 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 08:35:21 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 08:35:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hpq3qqdIu7/Render', 08:35:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:21 INFO - 'HOME': '/Users/cltbld', 08:35:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:21 INFO - 'LOGNAME': 'cltbld', 08:35:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:21 INFO - 'MOZ_NO_REMOTE': '1', 08:35:21 INFO - 'NO_EM_RESTART': '1', 08:35:21 INFO - 'PAGER': '/bin/cat', 08:35:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:21 INFO - 'PWD': '/builds/slave/test', 08:35:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:21 INFO - 'SHELL': '/bin/bash', 08:35:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ndLLBMP4SO/Listeners', 08:35:21 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 08:35:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:21 INFO - 'USER': 'cltbld', 08:35:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:21 INFO - 'XPC_FLAGS': '0x0', 08:35:21 INFO - 'XPC_SERVICE_NAME': '0', 08:35:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:21 INFO - Downloading/unpacking jsonschema==2.5.1 08:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:35:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:35:22 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 08:35:22 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 08:35:22 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 08:35:22 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 08:35:23 INFO - Downloading functools32-3.2.3-2.tar.gz 08:35:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:35:23 INFO - warning: no files found matching '*.txt' 08:35:23 INFO - no previously-included directories found matching 'build' 08:35:23 INFO - no previously-included directories found matching 'dist' 08:35:23 INFO - no previously-included directories found matching '.git*' 08:35:23 INFO - Installing collected packages: jsonschema, functools32 08:35:23 INFO - Running setup.py install for functools32 08:35:23 INFO - warning: no files found matching '*.txt' 08:35:23 INFO - no previously-included directories found matching 'build' 08:35:23 INFO - no previously-included directories found matching 'dist' 08:35:23 INFO - no previously-included directories found matching '.git*' 08:35:23 INFO - Successfully installed jsonschema functools32 08:35:23 INFO - Cleaning up... 08:35:23 INFO - Return code: 0 08:35:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:35:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e9adc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda1d8e430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e703d30>, '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 0x10e6cdcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6f4be8>, '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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 08:35:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:35:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:35:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hpq3qqdIu7/Render', 08:35:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:23 INFO - 'HOME': '/Users/cltbld', 08:35:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:23 INFO - 'LOGNAME': 'cltbld', 08:35:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:23 INFO - 'MOZ_NO_REMOTE': '1', 08:35:23 INFO - 'NO_EM_RESTART': '1', 08:35:23 INFO - 'PAGER': '/bin/cat', 08:35:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:23 INFO - 'PWD': '/builds/slave/test', 08:35:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:23 INFO - 'SHELL': '/bin/bash', 08:35:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ndLLBMP4SO/Listeners', 08:35:23 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 08:35:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:23 INFO - 'USER': 'cltbld', 08:35:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:23 INFO - 'XPC_FLAGS': '0x0', 08:35:23 INFO - 'XPC_SERVICE_NAME': '0', 08:35:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:35:23 INFO - Cleaning up... 08:35:23 INFO - Return code: 0 08:35:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:35:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e9adc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda1d8e430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e703d30>, '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 0x10e6cdcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6f4be8>, '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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 08:35:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:35:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:35:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hpq3qqdIu7/Render', 08:35:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:23 INFO - 'HOME': '/Users/cltbld', 08:35:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:23 INFO - 'LOGNAME': 'cltbld', 08:35:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:23 INFO - 'MOZ_NO_REMOTE': '1', 08:35:23 INFO - 'NO_EM_RESTART': '1', 08:35:23 INFO - 'PAGER': '/bin/cat', 08:35:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:23 INFO - 'PWD': '/builds/slave/test', 08:35:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:23 INFO - 'SHELL': '/bin/bash', 08:35:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ndLLBMP4SO/Listeners', 08:35:23 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 08:35:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:23 INFO - 'USER': 'cltbld', 08:35:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:23 INFO - 'XPC_FLAGS': '0x0', 08:35:23 INFO - 'XPC_SERVICE_NAME': '0', 08:35:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:24 INFO - Downloading/unpacking blobuploader==1.2.4 08:35:24 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 08:35:24 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 08:35:24 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 08:35:24 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 08:35:25 INFO - Downloading blobuploader-1.2.4.tar.gz 08:35:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:35:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:35:25 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 08:35:25 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 08:35:25 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 08:35:25 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 08:35:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:35:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:35:25 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 08:35:25 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 08:35:25 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 08:35:25 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 08:35:26 INFO - Downloading docopt-0.6.1.tar.gz 08:35:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:35:26 INFO - Installing collected packages: blobuploader, requests, docopt 08:35:26 INFO - Running setup.py install for blobuploader 08:35:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:35:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:35:26 INFO - Running setup.py install for requests 08:35:27 INFO - Running setup.py install for docopt 08:35:27 INFO - Successfully installed blobuploader requests docopt 08:35:27 INFO - Cleaning up... 08:35:27 INFO - Return code: 0 08:35:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35:27 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')]} 08:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:27 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')]} 08:35:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:27 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 0x10e9adc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda1d8e430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e703d30>, '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 0x10e6cdcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6f4be8>, '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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 08:35:27 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 08:35:27 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 08:35:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hpq3qqdIu7/Render', 08:35:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:27 INFO - 'HOME': '/Users/cltbld', 08:35:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:27 INFO - 'LOGNAME': 'cltbld', 08:35:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:27 INFO - 'MOZ_NO_REMOTE': '1', 08:35:27 INFO - 'NO_EM_RESTART': '1', 08:35:27 INFO - 'PAGER': '/bin/cat', 08:35:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:27 INFO - 'PWD': '/builds/slave/test', 08:35:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:27 INFO - 'SHELL': '/bin/bash', 08:35:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ndLLBMP4SO/Listeners', 08:35:27 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 08:35:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:27 INFO - 'USER': 'cltbld', 08:35:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:27 INFO - 'XPC_FLAGS': '0x0', 08:35:27 INFO - 'XPC_SERVICE_NAME': '0', 08:35:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:35:27 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-lJl7fk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:35:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:35:27 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-wL4jV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:35:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:35:27 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-AB4DfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:35:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:35:27 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-AwG58A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:35:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:35:27 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-1sKrJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:35:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:35:27 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-KO6_ZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:35:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-DWwLvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:35:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-mK8fqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:35:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-lkdoPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:35:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-ANqXXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:35:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-lwKfKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:35:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-HNPtv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:35:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-8R16IF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:35:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-oTWFSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-UXXbv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:35:29 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-r1UuzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:35:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:35:29 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Mu8wzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:35:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:35:29 INFO - Running setup.py install for manifestparser 08:35:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:35:29 INFO - Running setup.py install for mozcrash 08:35:29 INFO - Running setup.py install for mozdebug 08:35:29 INFO - Running setup.py install for mozdevice 08:35:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:35:29 INFO - Running setup.py install for mozfile 08:35:29 INFO - Running setup.py install for mozhttpd 08:35:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:35:30 INFO - Running setup.py install for mozinfo 08:35:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:35:30 INFO - Running setup.py install for mozInstall 08:35:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:35:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:35:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:35:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:35:30 INFO - Running setup.py install for mozleak 08:35:30 INFO - Running setup.py install for mozlog 08:35:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:35:30 INFO - Running setup.py install for moznetwork 08:35:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:35:30 INFO - Running setup.py install for mozprocess 08:35:30 INFO - Running setup.py install for mozprofile 08:35:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:35:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:35:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:35:31 INFO - Running setup.py install for mozrunner 08:35:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:35:31 INFO - Running setup.py install for mozscreenshot 08:35:31 INFO - Running setup.py install for moztest 08:35:31 INFO - Running setup.py install for mozversion 08:35:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:35:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:35:31 INFO - Cleaning up... 08:35:31 INFO - Return code: 0 08:35:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:31 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 0x10e9adc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda1d8e430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e703d30>, '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 0x10e6cdcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6f4be8>, '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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 08:35:31 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 08:35:31 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 08:35:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hpq3qqdIu7/Render', 08:35:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:31 INFO - 'HOME': '/Users/cltbld', 08:35:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:31 INFO - 'LOGNAME': 'cltbld', 08:35:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:31 INFO - 'MOZ_NO_REMOTE': '1', 08:35:31 INFO - 'NO_EM_RESTART': '1', 08:35:31 INFO - 'PAGER': '/bin/cat', 08:35:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:31 INFO - 'PWD': '/builds/slave/test', 08:35:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:31 INFO - 'SHELL': '/bin/bash', 08:35:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ndLLBMP4SO/Listeners', 08:35:31 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 08:35:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:31 INFO - 'USER': 'cltbld', 08:35:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:31 INFO - 'XPC_FLAGS': '0x0', 08:35:31 INFO - 'XPC_SERVICE_NAME': '0', 08:35:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:35:32 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-hjeyFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:35:32 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)) 08:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:35:32 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-hJ5xoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:35:32 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)) 08:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:35:32 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-mlYqpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:35:32 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)) 08:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:35:32 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Ws3CrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:35:32 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)) 08:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:35:32 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-S9pUne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:35:32 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)) 08:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:35:32 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-6pkB8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:35:32 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)) 08:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:35:32 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-MNIOLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:35:32 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)) 08:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:35:32 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-c6ajqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:35:32 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)) 08:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:35:32 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-s6C_Le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:35:32 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)) 08:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:35:32 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-m5OYJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:35:33 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)) 08:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:35:33 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-3vKDwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:35:33 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)) 08:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:35:33 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-5qxOr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:35:33 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)) 08:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:35:33 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Gcmcru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:35:33 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)) 08:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:35:33 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-_eCnme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:35:33 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)) 08:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:33 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-TR0V2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:33 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)) 08:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:35:33 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-4YZvhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:35:33 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)) 08:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:35:33 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-NfhYXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:35:33 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)) 08:35:33 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)) 08:35:33 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)) 08:35:33 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)) 08:35:33 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)) 08:35:33 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)) 08:35:33 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)) 08:35:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:35: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 08:35: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 08:35: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 08:35: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 08:35:35 INFO - Downloading blessings-1.6.tar.gz 08:35:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:35:35 INFO - Installing collected packages: blessings 08:35:35 INFO - Running setup.py install for blessings 08:35:35 INFO - Successfully installed blessings 08:35:35 INFO - Cleaning up... 08:35:35 INFO - Return code: 0 08:35:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:35:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:35:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:35:35 INFO - Reading from file tmpfile_stdout 08:35:35 INFO - Current package versions: 08:35:35 INFO - blessings == 1.6 08:35:35 INFO - blobuploader == 1.2.4 08:35:35 INFO - docopt == 0.6.1 08:35:35 INFO - functools32 == 3.2.3-2 08:35:35 INFO - jsonschema == 2.5.1 08:35:35 INFO - manifestparser == 1.1 08:35:35 INFO - mozInstall == 1.13 08:35:35 INFO - mozcrash == 1.0 08:35:35 INFO - mozdebug == 0.1 08:35:35 INFO - mozdevice == 0.51 08:35:35 INFO - mozfile == 1.2 08:35:35 INFO - mozhttpd == 0.7 08:35:35 INFO - mozinfo == 0.10 08:35:35 INFO - mozleak == 0.1 08:35:35 INFO - mozlog == 3.4 08:35:35 INFO - moznetwork == 0.27 08:35:35 INFO - mozprocess == 0.25 08:35:35 INFO - mozprofile == 0.28 08:35:35 INFO - mozrunner == 6.13 08:35:35 INFO - mozscreenshot == 0.1 08:35:35 INFO - mozsystemmonitor == 0.3 08:35:35 INFO - moztest == 0.8 08:35:35 INFO - mozversion == 1.4 08:35:35 INFO - psutil == 3.1.1 08:35:35 INFO - requests == 1.2.3 08:35:35 INFO - wsgiref == 0.1.2 08:35:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35:35 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')]} 08:35:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:35 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')]} 08:35:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:35 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 0x10e9adc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda1d8e430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e703d30>, '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 0x10e6cdcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6f4be8>, '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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 08:35:35 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 08:35:35 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 08:35:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hpq3qqdIu7/Render', 08:35:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:35 INFO - 'HOME': '/Users/cltbld', 08:35:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:35 INFO - 'LOGNAME': 'cltbld', 08:35:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:35 INFO - 'MOZ_NO_REMOTE': '1', 08:35:35 INFO - 'NO_EM_RESTART': '1', 08:35:35 INFO - 'PAGER': '/bin/cat', 08:35:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:35 INFO - 'PWD': '/builds/slave/test', 08:35:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:35 INFO - 'SHELL': '/bin/bash', 08:35:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ndLLBMP4SO/Listeners', 08:35:35 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 08:35:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:35 INFO - 'USER': 'cltbld', 08:35:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:35 INFO - 'XPC_FLAGS': '0x0', 08:35:35 INFO - 'XPC_SERVICE_NAME': '0', 08:35:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:36 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)) 08:35:36 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)) 08:35:36 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)) 08:35:36 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)) 08:35:36 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)) 08:35:36 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)) 08:35:36 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)) 08:35:36 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)) 08:35:36 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)) 08:35:36 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:35: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 08:35: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 08:35: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 08:35: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 08:35:37 INFO - Downloading marionette_driver-1.4.0.tar.gz 08:35:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 08:35:37 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)) 08:35:37 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)) 08:35:37 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)) 08:35:37 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)) 08:35:37 INFO - Installing collected packages: marionette-driver 08:35:37 INFO - Running setup.py install for marionette-driver 08:35:37 INFO - Successfully installed marionette-driver 08:35:37 INFO - Cleaning up... 08:35:37 INFO - Return code: 0 08:35:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:35: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')]} 08:35:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35: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')]} 08:35:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:37 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 0x10e9adc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9b1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda1d8e430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e703d30>, '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 0x10e6cdcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6f4be8>, '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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 08:35:37 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 08:35:37 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 08:35:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hpq3qqdIu7/Render', 08:35:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:37 INFO - 'HOME': '/Users/cltbld', 08:35:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:37 INFO - 'LOGNAME': 'cltbld', 08:35:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:37 INFO - 'MOZ_NO_REMOTE': '1', 08:35:37 INFO - 'NO_EM_RESTART': '1', 08:35:37 INFO - 'PAGER': '/bin/cat', 08:35:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:37 INFO - 'PWD': '/builds/slave/test', 08:35:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:37 INFO - 'SHELL': '/bin/bash', 08:35:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ndLLBMP4SO/Listeners', 08:35:37 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 08:35:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:37 INFO - 'USER': 'cltbld', 08:35:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:37 INFO - 'XPC_FLAGS': '0x0', 08:35:37 INFO - 'XPC_SERVICE_NAME': '0', 08:35:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:35:37 INFO - Cleaning up... 08:35:38 INFO - Return code: 0 08:35:38 INFO - Running post-action listener: _resource_record_post_action 08:35:38 INFO - Running post-action listener: _start_resource_monitoring 08:35:38 INFO - Starting resource monitoring. 08:35:38 INFO - [mozharness: 2017-06-13 15:35:38.036249Z] Finished create-virtualenv step (success) 08:35:38 INFO - [mozharness: 2017-06-13 15:35:38.037221Z] Running install step. 08:35:38 INFO - Running pre-action listener: _resource_record_pre_action 08:35:38 INFO - Running main action method: install 08:35:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:35:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:35:38 INFO - Reading from file tmpfile_stdout 08:35:38 INFO - Detecting whether we're running mozinstall >=1.0... 08:35:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:35:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:35:38 INFO - Reading from file tmpfile_stdout 08:35:38 INFO - Output received: 08:35:38 INFO - Usage: mozinstall [options] installer 08:35:38 INFO - Options: 08:35:38 INFO - -h, --help show this help message and exit 08:35:38 INFO - -d DEST, --destination=DEST 08:35:38 INFO - Directory to install application into. [default: 08:35:38 INFO - "/builds/slave/test"] 08:35:38 INFO - --app=APP Application being installed. [default: firefox] 08:35:38 INFO - mkdir: /builds/slave/test/build/application 08:35:38 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'] 08:35:38 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 08:35:54 INFO - Reading from file tmpfile_stdout 08:35:54 INFO - Output received: 08:35:54 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:35:54 INFO - Running post-action listener: _resource_record_post_action 08:35:54 INFO - [mozharness: 2017-06-13 15:35:54.121465Z] Finished install step (success) 08:35:54 INFO - [mozharness: 2017-06-13 15:35:54.121602Z] Running setup-mitmproxy step. 08:35:54 INFO - Running pre-action listener: _resource_record_pre_action 08:35:54 INFO - Running main action method: setup_mitmproxy 08:35:54 INFO - Skipping: mitmproxy is not required 08:35:54 INFO - Running post-action listener: _resource_record_post_action 08:35:54 INFO - [mozharness: 2017-06-13 15:35:54.122239Z] Finished setup-mitmproxy step (success) 08:35:54 INFO - [mozharness: 2017-06-13 15:35:54.122362Z] Running run-tests step. 08:35:54 INFO - Running pre-action listener: _resource_record_pre_action 08:35:54 INFO - Running main action method: run_tests 08:35:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:35:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:35:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:35:54 INFO - 2017-06-13 08:35:54.136 screenresolution[1626:7605] starting screenresolution argv=screenresolution get 08:35:54 INFO - 2017-06-13 08:35:54.139 screenresolution[1626:7605] Display 0: 1600x1200x32@60 08:35:54 INFO - 2017-06-13 08:35:54.145 screenresolution[1627:7607] starting screenresolution argv=screenresolution list 08:35:54 INFO - Available Modes on Display 0 08:35:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:35:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:35:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:35:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:35:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:35:54 INFO - Intel Iris: 08:35:54 INFO - Chipset Model: Intel Iris 08:35:54 INFO - Type: GPU 08:35:54 INFO - Bus: Built-In 08:35:54 INFO - VRAM (Dynamic, Max): 1536 MB 08:35:54 INFO - Vendor: Intel (0x8086) 08:35:54 INFO - Device ID: 0x0a2e 08:35:54 INFO - Revision ID: 0x0009 08:35:54 INFO - Displays: 08:35:54 INFO - Display: 08:35:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:35:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:35:54 INFO - Display Serial Number: 1600x1200 60 08:35:54 INFO - Main Display: Yes 08:35:54 INFO - Mirror: Off 08:35:54 INFO - Online: Yes 08:35:54 INFO - Rotation: Supported 08:35:54 INFO - Return code: 0 08:35:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:35:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:35:54 INFO - Python 2.7.3 08:35:54 INFO - Return code: 0 08:35:54 INFO - grabbing minidump binary from tooltool 08:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:54 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 0x10e703d30>, '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 0x10e6cdcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6f4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:35:54 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 08:35:54 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 08:35:54 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 08:35:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:35:54 INFO - Return code: 0 08:35:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:35:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:35:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:35:54 INFO - ENV: RUST_BACKTRACE is now 1 08:35:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:35:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:35:54 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/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--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 08:35:54 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/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0320 --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 08:35:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hpq3qqdIu7/Render', 08:35:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:54 INFO - 'HOME': '/Users/cltbld', 08:35:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:54 INFO - 'LOGNAME': 'cltbld', 08:35:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:35:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:35:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:54 INFO - 'MOZ_NO_REMOTE': '1', 08:35:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:35:54 INFO - 'NO_EM_RESTART': '1', 08:35:54 INFO - 'PAGER': '/bin/cat', 08:35:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:54 INFO - 'PWD': '/builds/slave/test', 08:35:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:35:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:54 INFO - 'RUST_BACKTRACE': '1', 08:35:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:35:54 INFO - 'SHELL': '/bin/bash', 08:35:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ndLLBMP4SO/Listeners', 08:35:54 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 08:35:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:54 INFO - 'USER': 'cltbld', 08:35:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:54 INFO - 'XPC_FLAGS': '0x0', 08:35:54 INFO - 'XPC_SERVICE_NAME': '0', 08:35:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:54 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/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--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 08:35:54 INFO - mozversion application_buildid: 20170613064551 08:35:54 INFO - mozversion application_changeset: e8dd9736241ea1989feaf3c85a71593c8a6f3bbb 08:35:54 INFO - mozversion application_display_name: Firefox 08:35:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:35:54 INFO - mozversion application_name: Firefox 08:35:54 INFO - mozversion application_remotingname: firefox 08:35:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:35:54 INFO - mozversion application_vendor: Mozilla 08:35:54 INFO - mozversion application_version: 55.0 08:35:54 INFO - mozversion platform_buildid: 20170613064551 08:35:54 INFO - mozversion platform_changeset: e8dd9736241ea1989feaf3c85a71593c8a6f3bbb 08:35:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:35:54 INFO - mozversion platform_version: 55.0 08:35:54 INFO - using testdate: 1497368154 08:35:54 INFO - actual date: 1497368154 08:35:54 INFO - starting webserver on 'localhost:49228' 08:35:54 INFO - SUITE-START | Running 2 tests 08:35:54 INFO - TEST-START | dromaeo_css 08:35:54 INFO - operating with platform_type : mac_ 08:35:54 INFO - Initialising browser for dromaeo_css test... 08:35:54 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpZbqhvz/profile 08:35:54 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 08:36:27 INFO - TEST-INFO | 1637: killed by SIGTERM 08:36:27 INFO - Browser initialized. 08:36:27 INFO - Running cycle 1/1 for dromaeo_css test... 08:36:27 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpZbqhvz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:36:29 INFO - PID 1647 | 2017-06-13 08:36:29.471 plugin-container[1648:8290] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9343, name = 'com.apple.tsm.portname' 08:36:29 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:36:29 INFO - PID 1647 | 2017-06-13 08:36:29.472 plugin-container[1648:8290] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9523, name = 'com.apple.CFPasteboardClient' 08:36:29 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:36:29 INFO - PID 1647 | 2017-06-13 08:36:29.472 plugin-container[1648:8290] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:36:39 INFO - PID 1647 | RSS: Main: 184942592 08:36:39 INFO - PID 1647 | 08:39:42 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 08:39:44 INFO - PID 1647 | RSS: Main: 204099584 08:39:44 INFO - PID 1647 | 08:42:45 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 08:42:46 INFO - PID 1647 | RSS: Main: 210513920 08:42:46 INFO - PID 1647 | 08:45:48 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 08:45:48 INFO - PID 1647 | RSS: Main: 212766720 08:45:48 INFO - PID 1647 | 08:48:50 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 08:48:51 INFO - PID 1647 | RSS: Main: 214560768 08:48:51 INFO - PID 1647 | 08:51:53 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 08:51:54 INFO - PID 1647 | RSS: Main: 216064000 08:51:54 INFO - PID 1647 | 08:54:56 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 08:54:56 INFO - PID 1647 | RSS: Main: 214544384 08:54:56 INFO - PID 1647 | 08:54:56 INFO - PID 1647 | __start_tp_report 08:54:56 INFO - PID 1647 | _x_x_mozilla_page_load 08:54:56 INFO - PID 1647 | _x_x_mozilla_page_load_details 08:54:56 INFO - PID 1647 | |i|pagename|runs| 08:54:56 INFO - PID 1647 | |0;dojo.html;3414.180596656803;3661.6338366163372;3783.7891874600127;3850.1529663474043;3908.7263891527596;5246.816361427346;6338;6344.492440604752;6353.047042943549;6401.871962560746;6327.9367206327925;6479.092926990225;6536.934630653704;6548.574342667724;6587.143671322721;5007.4491805901325;5733.797255405067;5835.095560188156;5964.552658550626;5996.040633498641;6009.789657361993;6013.518918486532;6013.939860601382;6036.034234522477;6049.909251361233;5632.098864181752;5837.182794408786;5850.970745146247;5906.704664766766;5915.822525324204;5643.915341269884;5717.857053573653;5791.623544469596;5845.503132233765;5868.295804503444;5724.026915424388;5751.108578170349;5759.2512973313205;5788.6816225107605;5836.212111364954;6412;6504.38208370204;6505.707243174022;6517.4134327910715;6548.476121910236;7116.964415177943;7122.465815063839;7133.678984445713;7162.4628152888745;7165.498415110892;6945.166580010431;6992.79021629352;7021.508494405393;7033.261507541737;7148.678309476086;6590.538662293689;6600.702968366435;6636.867262654817;6704.731810727565;6710.899336510005;7413.443991700537;7451.590162541004;7466.85066298671;7469.775906722807;7477.1775104738435;6741.0899528564;6980.336867997532;7032.12098487689;7051.1891132519395;7053.506254562131;7014.789556313319;7020.122484689414;7024.473164512579;7027.297270272932;7048.859022819515;5784.421557844182;6926.445884329342;6926.9307306926285;7008.544444611084;7106.431485481149;5965.492933100648;7118.323759242864;7123.821904452327;7127.394171495377;7142.5714457132735;5494.945050549523;5544.16837474374;5598;5614.326280846324;5706.3152421709665;4962.801487940515;4988.35151430319;4992.226204938241;5101.413337466239;5124.308218390544;6505.707243174069;6536.444402225864;6704;6742.022406751092;6761.560498567577;7253.8549229015125;7263.2736726328;7271.709131634675;7292.781216563511;7300.7079716811795;6713.798586042427;6728.495362847806;6730;6731.932680673131;6742.662866856637;6063.514918806485;6251.562390632612;6305.936940630627;6326.398992095744;6351.04734289851;7136.250693677193;7241.348278655052;7249.601271930094;7371.520851144658;7453.217412171861;5967.9701601491715;7184;7189.029481019996;7205.315495027964;7210.495265331377;7326.377257933135;7384.926150738639;7385.409167266714;7387.852242955219;7403.14863790671;7068.399186069241;7071.893921591078;7079.716811327489;7092.113485814273;7097.041899343522;5977.462028417463;6383.648899310582;6979.127609048869;7000.649967501503;7045.260247674126;6842.281560436281;6854.897176542256;6857.794266172023;6946.097007389007;6992;6916.585004899722;6982.301769822977;7001.50989430735;7002.509824312249;7019.543729657556;5526.005319042611;6515.967420163059;6885.552439091443;6900.7929762107215;6902.102726645705;6769.966150169218;6864;6875.174979002351;6989.860202796017;7015.403690686348;6668.899966500409;6694.732210711517;6708.597484150967;6729;6779.220389655055 08:54:56 INFO - PID 1647 | |1;ext.html;694.5069001009284;850.838340715264;858.8754630578562;885.3890815337419;893.0399820193294;41086;41671.374929376085;42051.78974105129;42105.36841947373;42183.78908105463;41336.58663413365;41401.964950876245;41495;41590.7920460398;41700.58299417005;7920.603969801501;8003.559804210766;8060.596970151499;8063.919360806405;8074.677012919506;8005.3195478384405;8108.351331893464;8109.391795615323;8132;8134.918650813505;8607.956960215222;8775.78060548485;8785.736427907173;8814.471131732085;8824.691135810248;8745.825083498326;8786.209241168326;8842.955785221066;8860.158284962918;8862.867056994086;8643.222110010129;8656.740297791077;8733.912660873373;8737.169968852948;8764.430312029697;38647;38785.806070969746;38885.41671874896;38889.63886263996;38926.83219503419;3665.248624032067;3668.761530500509;3702.648248416396;3725.701943844486;3761.4733937248807;31893.8405307972;32234.83882580572;32345;32382.514262286084;32431.837840810644;3252.6909943555324;3318.6183588887347;3322.96677033229;3343.1140747701634;3360.9327813443592;270964;271939;274298.6285068582;335399;336155;41362.586374136474;43201.56798431976;51828.74085629548;51958.701032473735;51961;54102.72948635231;54165.72917135468;54220.18669719958;54337.1849422259;54500.18249726258;16543;16570.75143872843;16607.83392166087;16670.499885003468;16682.916585416995;11271.943640281746;13691.315434228249;13693.246871422167;13698.931505342409;13706.45174193041;14332.49836255747;14430.422783088768;14437.989340746048;14500.477449867612;14531;6288.496920246369;6333.334999825044;6343.5876668016435;6360.173177486897;6393.6163830170335;1028.2442404832443;1038.5638032026568;1039.656913218636;1089.2810744908322;1101.5869049106584;20668.48328791762;23430.06279748825;25341.239762807145;25695.48609027809;25700.48599028009;1149.3678476837872;1180.1856718864128;1210.0017485574435;1215.9027277817754;1218.8842060004285;12990.480380784851;13074.346282685829;13088.672783180305;13100.934495327463;13142.014348923869;318995;320815;321818;321943.3902830471;322369.776302234;55743;58567;58648.706756465945;58845.41154588485;60173.398266017655;1545.729497337984;1604.1738504670338;1607.389192106992;1608.6300150965344;1621.2056092514583;20097.6985345217;23611.29166125019;24176.51646967086;25216.49567008613;25281.74718252794;40307;40632.796836016816;40817.1836563273;40940;40948.79525602353;307448;307972;308409;308494;308630;30685;31441.685583143877;34535.65464345324;35532.11169720778;35830.28339433249;41758.95602609959;43937.78031109824;44516.66450006505;46269.07461850677;46282.76858615685;28353.71646283511;29427.705722943352;29842.70157298399;31826.840865795522;31936.36127277488;27093;28313.43373132567;30206.697933021267;30836.69163308338;31188.53217201789;36897.26205475822;36901.892943211744;37569.81215094016;37769.81115094407;38058.809705951295;30566;30966.845165774026;31265.68734312628;31591.684083158874;31969;22640.66039009383;24930.252092437255;25038.37404064913;25124;25152.874235628704 08:54:56 INFO - PID 1647 | |2;jquery.html;464.2595060877899;493.83703377885297;527.8020742221667;539.3339226055822;554.5535843645863;23931.76068239317;24258.87870560647;24847.62728559071;25115.497690046188;27094.32264193391;25312.114076007343;26029.349266268353;26119.738802612017;26192.738072619268;26285.211443656674;6788;6914.446844252448;6922.826929326782;6926.619035953021;6953.096097507317;6502.577332473374;6810.353016463428;6836.555623884432;6911.89632155518;6975.0932378790685;6791.728330866759;6794.762183323584;6805.455563554904;6806.55757375769;6807.115075040238;6772.8306792330095;6780.49146314026;6791.830204244884;6799.4900382471515;6806.999999999975;6265.279492858334;6281.371862813728;6281.654509002003;6284.12022316876;6284.277308109535;32120.518192227137;32560.348793024008;32817.50773738396;33455.99632011043;33671.66328336734;2267.353804165821;2304.308707387777;2318.988405057964;2320.3155069254612;2320.4662927526765;20743.27398541059;20852.270170543958;20950.58098838015;21070.894645526674;21083.262085827075;2266.4900397410615;2309.919152829643;2352.9647055294013;2353.752855950118;2362.267697013914;172784.99999999875;187566;194724;196134.01932990385;224104;14561.513945408096;16514.091724955244;16636.33454661824;16750.664986700198;16800.243989020524;18301;21922.89038554797;21998.780012199994;22215.777842221694;22242.221522246644;9623.230141588656;11081.667549973514;11083.168762342857;11100.888991110147;11190.160737944523;8362.958185209034;10334;10405.687829365132;10583.94708026455;10584.206184536191;7979.281864632211;8392.916070839336;9393.20157786582;9603.807923841485;9723.756906077404;2338.205010296507;2584.224732580218;2800.3979144484033;2805.9438811223663;2812.915612531627;964.1852634523816;974.6418892760246;979.1481411172325;1003.2861110695551;1029;18219.45341639753;21232;22082.33752987413;22820.885895570747;23247.418814529432;892.3753372639178;1007.1036777268237;1033.235405799718;1054.0618849224197;1062.7608788022633;10743.979321964402;11372.351009103799;11673.883261167279;14794.63013424673;15966.441174558835;610303.793924119;634792;642242;652769;656430;147093;176959;179881;181490.64782732542;181719.18280817286;1095.3373209208314;1142.2689478733616;1171;1171.3264872698232;1175.8177482490228;23153.42116447035;26091.515015352205;26206.34484137912;26271.343216418976;26275.817588208196;72420;74550.25449745433;75680;77332.2266777325;80180.1981980196;97980.51009744906;99323;99545.01819927122;101478.4926075365;102648;12058.939705301418;12260.816087758512;12588.181768185405;12685;12690.17513861633;18818.717719234497;19273.84356938588;19473.61052778965;19519.8048019518;19550.608987820448;15998.760018599498;16018;16048;16148.354065837704;16276.755848662517;10766.946165269124;12363.763623637564;13882.791758123434;14335.92832035833;14942.77585836235;16254.593635159215;16396.508104756398;16460.176991150154;16593.66812663764;16661.333546658;12250.938745306217;12338.691532711755;12444.191127576678;12530.624081277576;12618.621441356774;15556.844431555539;15831.762523561925;16186.838131619004;16235.594110146869;16251.512454625907 08:54:56 INFO - PID 1647 | |3;mootools.html;1228.9692757681057;1465.4211586423357;1474.6165996840825;1474.997002038614;1480.1341215388998;14105.435782568698;14602.831773458125;14737.557873263819;14751.409943602257;14770.335334909929;11769.529218831258;14474.421023159088;14544.345504452298;14544.345504452298;14596.270186490687;2867.2152758358975;2932.1057077591327;2967.095036014015;2969.2725282305864;2970.9702902971017;2938.7942844000927;2939.103573410109;2958.053422904671;2959.1566403574984;2961.718636729507;2918.4892643787357;2926.094417175475;2932.4868148074106;2940.059181062061;2941;2887.8556072196307;2893.392387598596;2903.6951120132503;2903.70962903709;2912.898048568311;2741.5476446386524;2772.7781777457753;2775.4449110178043;2778.70823563527;2782.5687018512162;14161.645958851112;14260.215688137148;14304.713905721826;14326.068805527608;14330.856691433057;7983.2415920487465;8179;8270.421070525066;8280.04779450359;8282.296004839596;4049.3116170250833;4365.738055716635;4987.551120399182;5190.4550022247495;5193.480651934814;1331.4674130347842;1459.6350912271932;1487.2861026707242;1508.2609521334577;1509.8943073984826;19357.806421935882;19904.40286791384;21605.67591486129;21681.67477487839;21784.782152178435;6344.809655710336;6906.8618627628175;7134.2509036551455;7162.391196748335;7172.462065345016;14862.702745945237;14885.851141488663;15007.474738384104;15026.872984526204;15068.095914245181;7765.53406795594;7773.455858089879;7932.563708996061;7933.167017463199;7939;7222.241664625139;7346.63266836656;7407.000054992614;7452.06849143857;7474.289942455458;5384.461553844584;5396.271503346998;5504.119340905516;5513.9448605513435;5522.502974732293;1584.516722399679;1607.1642745772133;1607.766873803292;1626.4388785868857;1629.380835282585;718.9173245076884;773.307889438949;829.576915772948;849.3799526345803;863.5336918064176;7094.326039026284;7217.6752046157;7439.330460258603;7501.137369202503;7576.24237576238;692.7679227458858;694.3854688600524;715.8031541326072;737.4616529933179;742.076115236534;4338.978305108518;4947.82682606107;5008.12357837377;5065.696058236444;5248.396434410015;18852.528686782665;19146;19506.70739938902;19605.21579136847;19632.3128690498;14313.069650472686;19061.332853350064;19113.139908704143;19269.325573604856;19558.022098895;9794.16749576294;9798.216142708567;9859.852102218472;9880.45657488845;9947.701568952943;7856.607169641609;8007.839843202987;8118.2693557580105;8144.592770361577;8453.281471075028;17526;17534.210960506553;18045.639087218446;18233.179506921955;18237.45287641373;17491.650166996842;17900.373486927896;18056.909715451864;18523.44429667112;18566.164522596246;13009.479620815062;13237.874780643368;13259;13267.80098298507;13282.070255082052;12895.48418063267;13038.348082595643;13169.209847409185;13186.274754888573;13202.339883006003;10302.278840481094;10586.682399528027;10667.89332106669;10903;10908.400037997986;10218.1825453965;10302.22733295021;10362.844557331797;10637.202209834448;10723.98122178423;9610.855837162308;9668.75828104303;9702.902970970483;9717.708468745948;9736.561854716696;5981.910271345846;6000.129981152621;6060.727267273071;6257.998720204928;6316.11574379578;6379.840503987438;6534.869302614016;7127.679254433667;7377.77866664001;7380.077490313711 08:54:56 INFO - PID 1647 | |4;prototype.html;452.7306252779595;464.29547883578164;486.9926951095734;491.38576779026215;496.68460527564986;5855.9121613175785;6079.300880398756;6277.811665650026;6585.308542603024;6742.258351581322;6055.364186760382;6059.727312270948;6228.875422491559;6391.296957334701;6469.74121035158;4053.9391909121387;4877.365942427479;4921.778519966592;4935.80256789728;4949.851504454872;4059.9594004059877;4219.430376899126;4276.6792490563175;4835.283474366602;4846.642939976812;4236.279832428495;4855.757212139397;4879.8780030499165;4896.730679812609;4903.9701662650705;4724.362211101509;4888.340074089988;4910.705357678551;4913.6560440769335;4930.950690493085;4303.687375350548;4318.179545886303;4318.719283246611;4335.241332766753;4337.089211265638;6308.337624549402;6311.305756366842;6322.399372059647;6342.175517182783;6361.236651601791;4911.140550403665;5541.889162216733;5855.8536036599;5858.736356863951;5876.9118463223085;7067.469939754487;7070.717171313193;7083.787486375417;7088.6455677216445;7090.220075791711;4944.777485013183;4958.429780575208;4965.900681986376;4969.701817890938;4983.476734942815;7485.737999170057;7521.5111017783665;7543.547387156788;7577.991832164481;7596.063003933961;5054.19132938728;5056.216286475602;5071.949280507221;5074.213496907985;5075.5178258065425;4514.548545145459;5156.381234251914;5158;5158.613104017214;5187.948120518822;4878.390201224847;5849.210356601804;5872.618279811884;5884.558658100659;5884.705764711833;7115;7123.928760712327;7126.928730712731;7141.714331426789;7150.320719531636;1432.2240421877007;1504.563676533793;1674.6901823162755;1730.9095269979832;1753.8509226715616;994.786120983992;1081.194510089988;1098.6264670012074;1123.3203911633507;1151.4012713389159;5626.943730562723;6414.743410263537;6533.281339052701;6647.4349680276755;6697.832554186184;6940.757073502403;7344.412447004333;7347.265273472717;7367.5211111277595;7385.187629360766;6665.633390163587;6672.799816005528;6688.896332105245;6691.799246022627;6696.866062678817;3335.883244086494;3454.516367708523;3787.62123787619;3795.5329036126736;3810.657040866335;7074.257202993715;7446.478746487802;7524.811879702942;7566.205548417447;7581.355584775356;7524.9247507525315;7536.8492630148185;7542.396608271325;7544.585047822423;7629.7329593463965;7467.551946883204;7469.253074692596;7496.587687677121;7513.962430187814;7552.8489430212185;6855.348741869714;7289;7308.269173082649;7313.16059068993;7360.815979600553;6376.93623063782;6768.390844823989;7054.611996340251;7154.782017574568;7252.2747725227055;6299.527535434952;6502.60984340941;7501.4247008128905;7595.886061708969;7611.4291428142005;4029.7783621901076;4852.665516982858;5009.373828271466;5018.774155162953;5027.572656324107;6689.093608321605;7116.644167791486;7167.749128780468;7305.780826575211;7318.743843965436;7054.153501579844;7067.6112813795735;7072.752453664097;7077.256888026683;7097.290270972861;5883.382244864399;7044.260352662896;7050.224475307815;7061.505694601534;7090.645467726697;5235.74342157887;6638.734450621921;6893.655317234218;6920.757773477904;6953.617551034539;6041.607295525777;7040.647967601702;7075.221725610076;7081.256468070985;7095.503314768123;6610.636414997029;6775.93224067753;6880.937982597896;6905.447564194953;6956.339147780953 08:54:56 INFO - PID 1647 | |5;yui.html;827.5159031966299;838.5723281126624;843.3506200225826;862.2283056664288;863.0721973878083;17008.319667213327;17137.400190993318;17335.39326123586;17456.214470348834;17462.738058929128;12480.50077996879;18461.815381846176;19067.427977160674;19071.80928190721;19105.61788764224;1057.434272664123;1084.0893649334557;1090.3675867996583;1090.7982023325671;1092.4210168610648;1068.8503609494676;1093.5571093707022;1095.507021840171;1096.5942601237552;1097.8023955687972;1116.9385683787389;1133.8412622232895;1147.5295128997113;1150.120158079066;1150.5340337163461;1110.350444989682;1113.704868209925;1116.6203490813123;1130.2992144870193;1131.298594871181;829.0755807274905;838.5513750143643;838.6896848166217;839.215333663027;839.9160083991613;18238.63522729538;18247.72628410575;18318.90840545789;18412.815871841245;18426;17369.565760855952;18351.174197161024;18475.983820889847;18489.90755046216;18504.722429163572;14694.632634184123;15028.774568381483;15061.623459413493;15119.017263877813;15201;3666.926661466782;3685.5577330720216;3689.151495156129;3720.479132921393;3722.3671975763914;92467.53766231194;127529;156854;158922;160816.19591902083;11523.654290371302;11863.228890122115;11866.288022718665;14097.78853317201;14145.009849310656;25356.492870142494;25525.61711574328;25569.616455752806;25696.100636477633;25718.842652080326;7908.485948413335;7922.643481043367;7934.404919631051;7958.562279074591;8002.799930001796;3818.6563209311294;3961.6632586230494;3999;4028.939565906514;4032.3145065338963;4805.3512775775525;4881.755912204376;4893.266010098514;4919.434265059493;4923.286123512071;880.4937161132375;1037.4034929915329;1040.3393844908537;1043.5929987304958;1095.6274866545414;617.274702224893;643.9034144878212;654.8101050695341;676.5805200775551;680.8765536864214;11745.769913273747;12099.516019359127;12350.505979760888;13384.464621415229;14317.212553309668;646.6863571167979;661.7121552124842;699.1156187422914;699.576756062585;712.74697482393;5606.6636001840025;6050.818475445656;6189.721462534107;6387.680615969183;6477.421416942095;118428;146738;147351;150006;151748;113391.43304283425;114981.85018149878;118768.81231187578;122071;123034.76965230233;101250.4937475308;104768.47615761873;104805.47597262118;105608.94391056144;108561.91438085517;12385.318807465675;12624.684382890502;12758.362081896053;12832.55086071983;13298.202107873558;48310.75844620755;49069.2639610399;49335.50664493309;50004.24993625171;50381.49618503768;85660.71508927246;88081.55959220162;88645.11354886368;88802;98333.50833245787;18208.90746555211;18247.178876950304;18302.26790928401;18541.07294635236;18572.721409179143;18925.882282450682;18962.905185473985;19332.323368682028;19628.214871405555;19762.60474790525;17874.016929069025;18067.18697658582;18185.545361366072;18667.533311666775;18734.063296834833;17826.46520604384;17920.372786952394;18076.276948921895;18120.63758724792;18587.90706046461;15528.689426211638;15979.520614381123;16016.199190040219;16464;18017;11983.04135669127;12016.939915300367;12093.15347925672;12143.271403715806;12245.69385765363;13606.183628982293;13627.79558306646;13675.784854000789;14336.928315358356;14695.92652036733 08:54:56 INFO - PID 1647 | __end_tp_report 08:54:56 INFO - PID 1647 | __start_cc_report 08:54:56 INFO - PID 1647 | _x_x_mozilla_cycle_collect,387 08:54:56 INFO - PID 1647 | __end_cc_report 08:54:56 INFO - PID 1647 | __startTimestamp1497369296579__endTimestamp 08:54:56 INFO - PID 1647 | 08:54:56 INFO - PID 1647 | ------- Summary: start ------- 08:54:56 INFO - PID 1647 | Number of tests: 6 08:54:56 INFO - PID 1647 | 08:54:56 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:6510.46 Median:6731.93 stddev:789.76 (11.7%) stddev-sans-first:753.90 08:54:56 INFO - PID 1647 | Values: 3414.2 3661.6 3783.8 3850.2 3908.7 5246.8 6338.0 6344.5 6353.0 6401.9 6327.9 6479.1 6536.9 6548.6 6587.1 5007.4 5733.8 5835.1 5964.6 5996.0 6009.8 6013.5 6013.9 6036.0 6049.9 5632.1 5837.2 5851.0 5906.7 5915.8 5643.9 5717.9 5791.6 5845.5 5868.3 5724.0 5751.1 5759.3 5788.7 5836.2 6412.0 6504.4 6505.7 6517.4 6548.5 7117.0 7122.5 7133.7 7162.5 7165.5 6945.2 6992.8 7021.5 7033.3 7148.7 6590.5 6600.7 6636.9 6704.7 6710.9 7413.4 7451.6 7466.9 7469.8 7477.2 6741.1 6980.3 7032.1 7051.2 7053.5 7014.8 7020.1 7024.5 7027.3 7048.9 5784.4 6926.4 6926.9 7008.5 7106.4 5965.5 7118.3 7123.8 7127.4 7142.6 5494.9 5544.2 5598.0 5614.3 5706.3 4962.8 4988.4 4992.2 5101.4 5124.3 6505.7 6536.4 6704.0 6742.0 6761.6 7253.9 7263.3 7271.7 7292.8 7300.7 6713.8 6728.5 6730.0 6731.9 6742.7 6063.5 6251.6 6305.9 6326.4 6351.0 7136.3 7241.3 7249.6 7371.5 7453.2 5968.0 7184.0 7189.0 7205.3 7210.5 7326.4 7384.9 7385.4 7387.9 7403.1 7068.4 7071.9 7079.7 7092.1 7097.0 5977.5 6383.6 6979.1 7000.6 7045.3 6842.3 6854.9 6857.8 6946.1 6992.0 6916.6 6982.3 7001.5 7002.5 7019.5 5526.0 6516.0 6885.6 6900.8 6902.1 6770.0 6864.0 6875.2 6989.9 7015.4 6668.9 6694.7 6708.6 6729.0 6779.2 08:54:56 INFO - PID 1647 | 08:54:56 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:46664.11 Median:169757.97 stddev:81202.05 (47.8%) stddev-sans-first:81356.49 08:54:56 INFO - PID 1647 | Values: 694.5 850.8 858.9 885.4 893.0 41086.0 41671.4 42051.8 42105.4 42183.8 41336.6 41402.0 41495.0 41590.8 41700.6 7920.6 8003.6 8060.6 8063.9 8074.7 8005.3 8108.4 8109.4 8132.0 8134.9 8608.0 8775.8 8785.7 8814.5 8824.7 8745.8 8786.2 8843.0 8860.2 8862.9 8643.2 8656.7 8733.9 8737.2 8764.4 38647.0 38785.8 38885.4 38889.6 38926.8 3665.2 3668.8 3702.6 3725.7 3761.5 31893.8 32234.8 32345.0 32382.5 32431.8 3252.7 3318.6 3323.0 3343.1 3360.9 270964.0 271939.0 274298.6 335399.0 336155.0 41362.6 43201.6 51828.7 51958.7 51961.0 54102.7 54165.7 54220.2 54337.2 54500.2 16543.0 16570.8 16607.8 16670.5 16682.9 11271.9 13691.3 13693.2 13698.9 13706.5 14332.5 14430.4 14438.0 14500.5 14531.0 6288.5 6333.3 6343.6 6360.2 6393.6 1028.2 1038.6 1039.7 1089.3 1101.6 20668.5 23430.1 25341.2 25695.5 25700.5 1149.4 1180.2 1210.0 1215.9 1218.9 12990.5 13074.3 13088.7 13100.9 13142.0 318995.0 320815.0 321818.0 321943.4 322369.8 55743.0 58567.0 58648.7 58845.4 60173.4 1545.7 1604.2 1607.4 1608.6 1621.2 20097.7 23611.3 24176.5 25216.5 25281.7 40307.0 40632.8 40817.2 40940.0 40948.8 307448.0 307972.0 308409.0 308494.0 308630.0 30685.0 31441.7 34535.7 35532.1 35830.3 41759.0 43937.8 44516.7 46269.1 46282.8 28353.7 29427.7 29842.7 31826.8 31936.4 27093.0 28313.4 30206.7 30836.7 31188.5 36897.3 36901.9 37569.8 37769.8 38058.8 30566.0 30966.8 31265.7 31591.7 31969.0 22640.7 24930.3 25038.4 25124.0 25152.9 08:54:56 INFO - PID 1647 | 08:54:56 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:43529.14 Median:12544.12 stddev:109878.46 (875.9%) stddev-sans-first:110139.12 08:54:56 INFO - PID 1647 | Values: 464.3 493.8 527.8 539.3 554.6 23931.8 24258.9 24847.6 25115.5 27094.3 25312.1 26029.3 26119.7 26192.7 26285.2 6788.0 6914.4 6922.8 6926.6 6953.1 6502.6 6810.4 6836.6 6911.9 6975.1 6791.7 6794.8 6805.5 6806.6 6807.1 6772.8 6780.5 6791.8 6799.5 6807.0 6265.3 6281.4 6281.7 6284.1 6284.3 32120.5 32560.3 32817.5 33456.0 33671.7 2267.4 2304.3 2319.0 2320.3 2320.5 20743.3 20852.3 20950.6 21070.9 21083.3 2266.5 2309.9 2353.0 2353.8 2362.3 172785.0 187566.0 194724.0 196134.0 224104.0 14561.5 16514.1 16636.3 16750.7 16800.2 18301.0 21922.9 21998.8 22215.8 22242.2 9623.2 11081.7 11083.2 11100.9 11190.2 8363.0 10334.0 10405.7 10583.9 10584.2 7979.3 8392.9 9393.2 9603.8 9723.8 2338.2 2584.2 2800.4 2805.9 2812.9 964.2 974.6 979.1 1003.3 1029.0 18219.5 21232.0 22082.3 22820.9 23247.4 892.4 1007.1 1033.2 1054.1 1062.8 10744.0 11372.4 11673.9 14794.6 15966.4 610303.8 634792.0 642242.0 652769.0 656430.0 147093.0 176959.0 179881.0 181490.6 181719.2 1095.3 1142.3 1171.0 1171.3 1175.8 23153.4 26091.5 26206.3 26271.3 26275.8 72420.0 74550.3 75680.0 77332.2 80180.2 97980.5 99323.0 99545.0 101478.5 102648.0 12058.9 12260.8 12588.2 12685.0 12690.2 18818.7 19273.8 19473.6 19519.8 19550.6 15998.8 16018.0 16048.0 16148.4 16276.8 10766.9 12363.8 13882.8 14335.9 14942.8 16254.6 16396.5 16460.2 16593.7 16661.3 12250.9 12338.7 12444.2 12530.6 12618.6 15556.8 15831.8 16186.8 16235.6 16251.5 08:54:56 INFO - PID 1647 | 08:54:56 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:8719.93 Median:2915.69 stddev:5846.26 (200.5%) stddev-sans-first:5835.56 08:54:56 INFO - PID 1647 | Values: 1229.0 1465.4 1474.6 1475.0 1480.1 14105.4 14602.8 14737.6 14751.4 14770.3 11769.5 14474.4 14544.3 14544.3 14596.3 2867.2 2932.1 2967.1 2969.3 2971.0 2938.8 2939.1 2958.1 2959.2 2961.7 2918.5 2926.1 2932.5 2940.1 2941.0 2887.9 2893.4 2903.7 2903.7 2912.9 2741.5 2772.8 2775.4 2778.7 2782.6 14161.6 14260.2 14304.7 14326.1 14330.9 7983.2 8179.0 8270.4 8280.0 8282.3 4049.3 4365.7 4987.6 5190.5 5193.5 1331.5 1459.6 1487.3 1508.3 1509.9 19357.8 19904.4 21605.7 21681.7 21784.8 6344.8 6906.9 7134.3 7162.4 7172.5 14862.7 14885.9 15007.5 15026.9 15068.1 7765.5 7773.5 7932.6 7933.2 7939.0 7222.2 7346.6 7407.0 7452.1 7474.3 5384.5 5396.3 5504.1 5513.9 5522.5 1584.5 1607.2 1607.8 1626.4 1629.4 718.9 773.3 829.6 849.4 863.5 7094.3 7217.7 7439.3 7501.1 7576.2 692.8 694.4 715.8 737.5 742.1 4339.0 4947.8 5008.1 5065.7 5248.4 18852.5 19146.0 19506.7 19605.2 19632.3 14313.1 19061.3 19113.1 19269.3 19558.0 9794.2 9798.2 9859.9 9880.5 9947.7 7856.6 8007.8 8118.3 8144.6 8453.3 17526.0 17534.2 18045.6 18233.2 18237.5 17491.7 17900.4 18056.9 18523.4 18566.2 13009.5 13237.9 13259.0 13267.8 13282.1 12895.5 13038.3 13169.2 13186.3 13202.3 10302.3 10586.7 10667.9 10903.0 10908.4 10218.2 10302.2 10362.8 10637.2 10724.0 9610.9 9668.8 9702.9 9717.7 9736.6 5981.9 6000.1 6060.7 6258.0 6316.1 6379.8 6534.9 7127.7 7377.8 7380.1 08:54:56 INFO - PID 1647 | 08:54:56 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5751.94 Median:6384.12 stddev:1803.23 (28.2%) stddev-sans-first:1763.88 08:54:56 INFO - PID 1647 | Values: 452.7 464.3 487.0 491.4 496.7 5855.9 6079.3 6277.8 6585.3 6742.3 6055.4 6059.7 6228.9 6391.3 6469.7 4053.9 4877.4 4921.8 4935.8 4949.9 4060.0 4219.4 4276.7 4835.3 4846.6 4236.3 4855.8 4879.9 4896.7 4904.0 4724.4 4888.3 4910.7 4913.7 4931.0 4303.7 4318.2 4318.7 4335.2 4337.1 6308.3 6311.3 6322.4 6342.2 6361.2 4911.1 5541.9 5855.9 5858.7 5876.9 7067.5 7070.7 7083.8 7088.6 7090.2 4944.8 4958.4 4965.9 4969.7 4983.5 7485.7 7521.5 7543.5 7578.0 7596.1 5054.2 5056.2 5071.9 5074.2 5075.5 4514.5 5156.4 5158.0 5158.6 5187.9 4878.4 5849.2 5872.6 5884.6 5884.7 7115.0 7123.9 7126.9 7141.7 7150.3 1432.2 1504.6 1674.7 1730.9 1753.9 994.8 1081.2 1098.6 1123.3 1151.4 5626.9 6414.7 6533.3 6647.4 6697.8 6940.8 7344.4 7347.3 7367.5 7385.2 6665.6 6672.8 6688.9 6691.8 6696.9 3335.9 3454.5 3787.6 3795.5 3810.7 7074.3 7446.5 7524.8 7566.2 7581.4 7524.9 7536.8 7542.4 7544.6 7629.7 7467.6 7469.3 7496.6 7514.0 7552.8 6855.3 7289.0 7308.3 7313.2 7360.8 6376.9 6768.4 7054.6 7154.8 7252.3 6299.5 6502.6 7501.4 7595.9 7611.4 4029.8 4852.7 5009.4 5018.8 5027.6 6689.1 7116.6 7167.7 7305.8 7318.7 7054.2 7067.6 7072.8 7077.3 7097.3 5883.4 7044.3 7050.2 7061.5 7090.6 5235.7 6638.7 6893.7 6920.8 6953.6 6041.6 7040.6 7075.2 7081.3 7095.5 6610.6 6775.9 6880.9 6905.4 6956.3 08:54:56 INFO - PID 1647 | 08:54:56 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:26305.31 Median:18153.09 stddev:39613.84 (218.2%) stddev-sans-first:39678.78 08:54:56 INFO - PID 1647 | Values: 827.5 838.6 843.4 862.2 863.1 17008.3 17137.4 17335.4 17456.2 17462.7 12480.5 18461.8 19067.4 19071.8 19105.6 1057.4 1084.1 1090.4 1090.8 1092.4 1068.9 1093.6 1095.5 1096.6 1097.8 1116.9 1133.8 1147.5 1150.1 1150.5 1110.4 1113.7 1116.6 1130.3 1131.3 829.1 838.6 838.7 839.2 839.9 18238.6 18247.7 18318.9 18412.8 18426.0 17369.6 18351.2 18476.0 18489.9 18504.7 14694.6 15028.8 15061.6 15119.0 15201.0 3666.9 3685.6 3689.2 3720.5 3722.4 92467.5 127529.0 156854.0 158922.0 160816.2 11523.7 11863.2 11866.3 14097.8 14145.0 25356.5 25525.6 25569.6 25696.1 25718.8 7908.5 7922.6 7934.4 7958.6 8002.8 3818.7 3961.7 3999.0 4028.9 4032.3 4805.4 4881.8 4893.3 4919.4 4923.3 880.5 1037.4 1040.3 1043.6 1095.6 617.3 643.9 654.8 676.6 680.9 11745.8 12099.5 12350.5 13384.5 14317.2 646.7 661.7 699.1 699.6 712.7 5606.7 6050.8 6189.7 6387.7 6477.4 118428.0 146738.0 147351.0 150006.0 151748.0 113391.4 114981.9 118768.8 122071.0 123034.8 101250.5 104768.5 104805.5 105608.9 108561.9 12385.3 12624.7 12758.4 12832.6 13298.2 48310.8 49069.3 49335.5 50004.2 50381.5 85660.7 88081.6 88645.1 88802.0 98333.5 18208.9 18247.2 18302.3 18541.1 18572.7 18925.9 18962.9 19332.3 19628.2 19762.6 17874.0 18067.2 18185.5 18667.5 18734.1 17826.5 17920.4 18076.3 18120.6 18587.9 15528.7 15979.5 16016.2 16464.0 18017.0 11983.0 12016.9 12093.2 12143.3 12245.7 13606.2 13627.8 13675.8 14336.9 14695.9 08:54:56 INFO - PID 1647 | -------- Summary: end -------- 08:54:56 INFO - PID 1647 | 08:54:57 INFO - TEST-INFO | 1647: exit 0 08:54:57 INFO - TEST-OK | dromaeo_css | took 1142329ms 08:54:57 INFO - TEST-START | kraken 08:54:57 INFO - operating with platform_type : mac_ 08:54:57 INFO - Initialising browser for kraken test... 08:54:57 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmplWEPgs/profile 08:54:57 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 08:55:29 INFO - TEST-INFO | 1656: killed by SIGTERM 08:55:29 INFO - Browser initialized. 08:55:29 INFO - Running cycle 1/1 for kraken test... 08:55:29 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmplWEPgs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:55:31 INFO - PID 1659 | 2017-06-13 08:55:31.601 plugin-container[1660:10866] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 08:55:31 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:55:31 INFO - PID 1659 | 2017-06-13 08:55:31.601 plugin-container[1660:10866] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 08:55:31 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:55:31 INFO - PID 1659 | 2017-06-13 08:55:31.601 plugin-container[1660:10866] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:55:41 INFO - PID 1659 | RSS: Main: 184737792 08:55:41 INFO - PID 1659 | 08:55:44 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 08:55:45 INFO - PID 1659 | RSS: Main: 198885376 08:55:45 INFO - PID 1659 | 08:55:47 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 08:55:48 INFO - PID 1659 | RSS: Main: 209182720 08:55:48 INFO - PID 1659 | 08:55:51 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 08:55:52 INFO - PID 1659 | RSS: Main: 213741568 08:55:52 INFO - PID 1659 | 08:55:54 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 08:55:54 INFO - PID 1659 | RSS: Main: 215252992 08:55:54 INFO - PID 1659 | 08:55:56 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:55:57 INFO - PID 1659 | RSS: Main: 215478272 08:55:57 INFO - PID 1659 | 08:56:00 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 08:56:00 INFO - PID 1659 | RSS: Main: 219611136 08:56:00 INFO - PID 1659 | 08:56:03 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 08:56:04 INFO - PID 1659 | RSS: Main: 219582464 08:56:04 INFO - PID 1659 | 08:56:07 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 08:56:07 INFO - PID 1659 | RSS: Main: 219897856 08:56:07 INFO - PID 1659 | 08:56:09 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:56:09 INFO - PID 1659 | RSS: Main: 219451392 08:56:09 INFO - PID 1659 | 08:56:11 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:56:12 INFO - PID 1659 | RSS: Main: 219238400 08:56:12 INFO - PID 1659 | 08:56:14 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:56:14 INFO - PID 1659 | RSS: Main: 219283456 08:56:14 INFO - PID 1659 | 08:56:17 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:56:18 INFO - PID 1659 | RSS: Main: 220495872 08:56:18 INFO - PID 1659 | 08:56:21 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:56:21 INFO - PID 1659 | RSS: Main: 219631616 08:56:21 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 08:56:23 INFO - PID 1659 | RSS: Main: 221638656 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | __start_tp_report 08:56:23 INFO - PID 1659 | _x_x_mozilla_page_load 08:56:23 INFO - PID 1659 | _x_x_mozilla_page_load_details 08:56:23 INFO - PID 1659 | |i|pagename|runs| 08:56:23 INFO - PID 1659 | |0;ai-astar;102;115;106;112;150;170;134;102;123;109 08:56:23 INFO - PID 1659 | |1;audio-beat-detection;143;109;144;139;110;125;107;144;142;112 08:56:23 INFO - PID 1659 | |2;audio-dft;194;204;195;196;193;199;202;197;200;219 08:56:23 INFO - PID 1659 | |3;audio-fft;66;84;67;156;58;81;83;88;120;58 08:56:23 INFO - PID 1659 | |4;audio-oscillator;69;71;137;99;99;88;110;70;69;107 08:56:23 INFO - PID 1659 | |5;imaging-gaussian-blur;90;89;88;89;89;88;88;88;89;88 08:56:23 INFO - PID 1659 | |6;imaging-darkroom;96;96;96;95;97;95;95;122;96;96 08:56:23 INFO - PID 1659 | |7;imaging-desaturate;132;91;89;90;93;93;92;91;92;90 08:56:23 INFO - PID 1659 | |8;json-parse-financial;48;59;44;49;50;51;51;50;53;51 08:56:23 INFO - PID 1659 | |9;json-stringify-tinderbox;54;56;53;59;57;57;58;55;54;55 08:56:23 INFO - PID 1659 | |10;stanford-crypto-aes;81;78;77;84;78;80;80;78;76;81 08:56:23 INFO - PID 1659 | |11;stanford-crypto-ccm;147;147;131;128;132;134;147;140;150;158 08:56:23 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;169;179;175;174;173;173;175;177;180;172 08:56:23 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;63;66;66;62;63;63;65;64;64;65 08:56:23 INFO - PID 1659 | __end_tp_report 08:56:23 INFO - PID 1659 | __start_cc_report 08:56:23 INFO - PID 1659 | _x_x_mozilla_cycle_collect,970 08:56:23 INFO - PID 1659 | __end_cc_report 08:56:23 INFO - PID 1659 | __startTimestamp1497369383598__endTimestamp 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | ------- Summary: start ------- 08:56:23 INFO - PID 1659 | Number of tests: 14 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:122.30 Median:119.00 stddev:22.62 (19.0%) stddev-sans-first:22.77 08:56:23 INFO - PID 1659 | Values: 102.0 115.0 106.0 112.0 150.0 170.0 134.0 102.0 123.0 109.0 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:140.50 stddev:16.47 (11.7%) stddev-sans-first:16.49 08:56:23 INFO - PID 1659 | Values: 143.0 109.0 144.0 139.0 110.0 125.0 107.0 144.0 142.0 112.0 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:199.90 Median:199.50 stddev:7.58 (3.8%) stddev-sans-first:7.73 08:56:23 INFO - PID 1659 | Values: 194.0 204.0 195.0 196.0 193.0 199.0 202.0 197.0 200.0 219.0 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:86.10 Median:74.00 stddev:30.60 (41.4%) stddev-sans-first:31.58 08:56:23 INFO - PID 1659 | Values: 66.0 84.0 67.0 156.0 58.0 81.0 83.0 88.0 120.0 58.0 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:70.50 stddev:22.78 (32.3%) stddev-sans-first:22.61 08:56:23 INFO - PID 1659 | Values: 69.0 71.0 137.0 99.0 99.0 88.0 110.0 70.0 69.0 107.0 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:89.00 stddev:0.70 (0.8%) stddev-sans-first:0.53 08:56:23 INFO - PID 1659 | Values: 90.0 89.0 88.0 89.0 89.0 88.0 88.0 88.0 89.0 88.0 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:98.40 Median:96.00 stddev:8.32 (8.7%) stddev-sans-first:8.77 08:56:23 INFO - PID 1659 | Values: 96.0 96.0 96.0 95.0 97.0 95.0 95.0 122.0 96.0 96.0 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:91.50 stddev:12.96 (14.2%) stddev-sans-first:1.39 08:56:23 INFO - PID 1659 | Values: 132.0 91.0 89.0 90.0 93.0 93.0 92.0 91.0 92.0 90.0 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:3.81 (7.5%) stddev-sans-first:3.92 08:56:23 INFO - PID 1659 | Values: 48.0 59.0 44.0 49.0 50.0 51.0 51.0 50.0 53.0 51.0 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:55.80 Median:56.50 stddev:1.93 (3.4%) stddev-sans-first:1.94 08:56:23 INFO - PID 1659 | Values: 54.0 56.0 53.0 59.0 57.0 57.0 58.0 55.0 54.0 55.0 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:79.30 Median:80.00 stddev:2.36 (2.9%) stddev-sans-first:2.42 08:56:23 INFO - PID 1659 | Values: 81.0 78.0 77.0 84.0 78.0 80.0 80.0 78.0 76.0 81.0 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:141.40 Median:147.00 stddev:9.87 (6.7%) stddev-sans-first:10.26 08:56:23 INFO - PID 1659 | Values: 147.0 147.0 131.0 128.0 132.0 134.0 147.0 140.0 150.0 158.0 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:175.00 stddev:3.30 (1.9%) stddev-sans-first:2.78 08:56:23 INFO - PID 1659 | Values: 169.0 179.0 175.0 174.0 173.0 173.0 175.0 177.0 180.0 172.0 08:56:23 INFO - PID 1659 | 08:56:23 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.50 stddev:1.37 (2.1%) stddev-sans-first:1.39 08:56:23 INFO - PID 1659 | Values: 63.0 66.0 66.0 62.0 63.0 63.0 65.0 64.0 64.0 65.0 08:56:23 INFO - PID 1659 | -------- Summary: end -------- 08:56:23 INFO - PID 1659 | 08:56:24 INFO - TEST-INFO | 1659: exit 0 08:56:24 INFO - TEST-OK | kraken | took 87268ms 08:56:24 INFO - SUITE-END | took 1229s 08:56:24 INFO - Completed test suite (00:20:30) 08:56:24 INFO - javascript benchmark 08:56:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8744.634002015879, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3414.180596656803, 3661.6338366163372, 3783.7891874600127, 3850.1529663474043, 3908.7263891527596, 5246.816361427346, 6338.0, 6344.492440604752, 6353.047042943549, 6401.871962560746, 6327.9367206327925, 6479.092926990225, 6536.934630653704, 6548.574342667724, 6587.143671322721, 5007.4491805901325, 5733.797255405067, 5835.095560188156, 5964.552658550626, 5996.040633498641, 6009.789657361993, 6013.518918486532, 6013.939860601382, 6036.034234522477, 6049.909251361233, 5632.098864181752, 5837.182794408786, 5850.970745146247, 5906.704664766766, 5915.822525324204, 5643.915341269884, 5717.857053573653, 5791.623544469596, 5845.503132233765, 5868.295804503444, 5724.026915424388, 5751.108578170349, 5759.2512973313205, 5788.6816225107605, 5836.212111364954, 6412.0, 6504.38208370204, 6505.707243174022, 6517.4134327910715, 6548.476121910236, 7116.964415177943, 7122.465815063839, 7133.678984445713, 7162.4628152888745, 7165.498415110892, 6945.166580010431, 6992.79021629352, 7021.508494405393, 7033.261507541737, 7148.678309476086, 6590.538662293689, 6600.702968366435, 6636.867262654817, 6704.731810727565, 6710.899336510005, 7413.443991700537, 7451.590162541004, 7466.85066298671, 7469.775906722807, 7477.1775104738435, 6741.0899528564, 6980.336867997532, 7032.12098487689, 7051.1891132519395, 7053.506254562131, 7014.789556313319, 7020.122484689414, 7024.473164512579, 7027.297270272932, 7048.859022819515, 5784.421557844182, 6926.445884329342, 6926.9307306926285, 7008.544444611084, 7106.431485481149, 5965.492933100648, 7118.323759242864, 7123.821904452327, 7127.394171495377, 7142.5714457132735, 5494.945050549523, 5544.16837474374, 5598.0, 5614.326280846324, 5706.3152421709665, 4962.801487940515, 4988.35151430319, 4992.226204938241, 5101.413337466239, 5124.308218390544, 6505.707243174069, 6536.444402225864, 6704.0, 6742.022406751092, 6761.560498567577, 7253.8549229015125, 7263.2736726328, 7271.709131634675, 7292.781216563511, 7300.7079716811795, 6713.798586042427, 6728.495362847806, 6730.0, 6731.932680673131, 6742.662866856637, 6063.514918806485, 6251.562390632612, 6305.936940630627, 6326.398992095744, 6351.04734289851, 7136.250693677193, 7241.348278655052, 7249.601271930094, 7371.520851144658, 7453.217412171861, 5967.9701601491715, 7184.0, 7189.029481019996, 7205.315495027964, 7210.495265331377, 7326.377257933135, 7384.926150738639, 7385.409167266714, 7387.852242955219, 7403.14863790671, 7068.399186069241, 7071.893921591078, 7079.716811327489, 7092.113485814273, 7097.041899343522, 5977.462028417463, 6383.648899310582, 6979.127609048869, 7000.649967501503, 7045.260247674126, 6842.281560436281, 6854.897176542256, 6857.794266172023, 6946.097007389007, 6992.0, 6916.585004899722, 6982.301769822977, 7001.50989430735, 7002.509824312249, 7019.543729657556, 5526.005319042611, 6515.967420163059, 6885.552439091443, 6900.7929762107215, 6902.102726645705, 6769.966150169218, 6864.0, 6875.174979002351, 6989.860202796017, 7015.403690686348, 6668.899966500409, 6694.732210711517, 6708.597484150967, 6729.0, 6779.220389655055], "value": 6458.354630351001, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.5069001009284, 850.838340715264, 858.8754630578562, 885.3890815337419, 893.0399820193294, 41086.0, 41671.374929376085, 42051.78974105129, 42105.36841947373, 42183.78908105463, 41336.58663413365, 41401.964950876245, 41495.0, 41590.7920460398, 41700.58299417005, 7920.603969801501, 8003.559804210766, 8060.596970151499, 8063.919360806405, 8074.677012919506, 8005.3195478384405, 8108.351331893464, 8109.391795615323, 8132.0, 8134.918650813505, 8607.956960215222, 8775.78060548485, 8785.736427907173, 8814.471131732085, 8824.691135810248, 8745.825083498326, 8786.209241168326, 8842.955785221066, 8860.158284962918, 8862.867056994086, 8643.222110010129, 8656.740297791077, 8733.912660873373, 8737.169968852948, 8764.430312029697, 38647.0, 38785.806070969746, 38885.41671874896, 38889.63886263996, 38926.83219503419, 3665.248624032067, 3668.761530500509, 3702.648248416396, 3725.701943844486, 3761.4733937248807, 31893.8405307972, 32234.83882580572, 32345.0, 32382.514262286084, 32431.837840810644, 3252.6909943555324, 3318.6183588887347, 3322.96677033229, 3343.1140747701634, 3360.9327813443592, 270964.0, 271939.0, 274298.6285068582, 335399.0, 336155.0, 41362.586374136474, 43201.56798431976, 51828.74085629548, 51958.701032473735, 51961.0, 54102.72948635231, 54165.72917135468, 54220.18669719958, 54337.1849422259, 54500.18249726258, 16543.0, 16570.75143872843, 16607.83392166087, 16670.499885003468, 16682.916585416995, 11271.943640281746, 13691.315434228249, 13693.246871422167, 13698.931505342409, 13706.45174193041, 14332.49836255747, 14430.422783088768, 14437.989340746048, 14500.477449867612, 14531.0, 6288.496920246369, 6333.334999825044, 6343.5876668016435, 6360.173177486897, 6393.6163830170335, 1028.2442404832443, 1038.5638032026568, 1039.656913218636, 1089.2810744908322, 1101.5869049106584, 20668.48328791762, 23430.06279748825, 25341.239762807145, 25695.48609027809, 25700.48599028009, 1149.3678476837872, 1180.1856718864128, 1210.0017485574435, 1215.9027277817754, 1218.8842060004285, 12990.480380784851, 13074.346282685829, 13088.672783180305, 13100.934495327463, 13142.014348923869, 318995.0, 320815.0, 321818.0, 321943.3902830471, 322369.776302234, 55743.0, 58567.0, 58648.706756465945, 58845.41154588485, 60173.398266017655, 1545.729497337984, 1604.1738504670338, 1607.389192106992, 1608.6300150965344, 1621.2056092514583, 20097.6985345217, 23611.29166125019, 24176.51646967086, 25216.49567008613, 25281.74718252794, 40307.0, 40632.796836016816, 40817.1836563273, 40940.0, 40948.79525602353, 307448.0, 307972.0, 308409.0, 308494.0, 308630.0, 30685.0, 31441.685583143877, 34535.65464345324, 35532.11169720778, 35830.28339433249, 41758.95602609959, 43937.78031109824, 44516.66450006505, 46269.07461850677, 46282.76858615685, 28353.71646283511, 29427.705722943352, 29842.70157298399, 31826.840865795522, 31936.36127277488, 27093.0, 28313.43373132567, 30206.697933021267, 30836.69163308338, 31188.53217201789, 36897.26205475822, 36901.892943211744, 37569.81215094016, 37769.81115094407, 38058.809705951295, 30566.0, 30966.845165774026, 31265.68734312628, 31591.684083158874, 31969.0, 22640.66039009383, 24930.252092437255, 25038.37404064913, 25124.0, 25152.874235628704], "value": 18154.181081106257, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.2595060877899, 493.83703377885297, 527.8020742221667, 539.3339226055822, 554.5535843645863, 23931.76068239317, 24258.87870560647, 24847.62728559071, 25115.497690046188, 27094.32264193391, 25312.114076007343, 26029.349266268353, 26119.738802612017, 26192.738072619268, 26285.211443656674, 6788.0, 6914.446844252448, 6922.826929326782, 6926.619035953021, 6953.096097507317, 6502.577332473374, 6810.353016463428, 6836.555623884432, 6911.89632155518, 6975.0932378790685, 6791.728330866759, 6794.762183323584, 6805.455563554904, 6806.55757375769, 6807.115075040238, 6772.8306792330095, 6780.49146314026, 6791.830204244884, 6799.4900382471515, 6806.999999999975, 6265.279492858334, 6281.371862813728, 6281.654509002003, 6284.12022316876, 6284.277308109535, 32120.518192227137, 32560.348793024008, 32817.50773738396, 33455.99632011043, 33671.66328336734, 2267.353804165821, 2304.308707387777, 2318.988405057964, 2320.3155069254612, 2320.4662927526765, 20743.27398541059, 20852.270170543958, 20950.58098838015, 21070.894645526674, 21083.262085827075, 2266.4900397410615, 2309.919152829643, 2352.9647055294013, 2353.752855950118, 2362.267697013914, 172784.99999999875, 187566.0, 194724.0, 196134.01932990385, 224104.0, 14561.513945408096, 16514.091724955244, 16636.33454661824, 16750.664986700198, 16800.243989020524, 18301.0, 21922.89038554797, 21998.780012199994, 22215.777842221694, 22242.221522246644, 9623.230141588656, 11081.667549973514, 11083.168762342857, 11100.888991110147, 11190.160737944523, 8362.958185209034, 10334.0, 10405.687829365132, 10583.94708026455, 10584.206184536191, 7979.281864632211, 8392.916070839336, 9393.20157786582, 9603.807923841485, 9723.756906077404, 2338.205010296507, 2584.224732580218, 2800.3979144484033, 2805.9438811223663, 2812.915612531627, 964.1852634523816, 974.6418892760246, 979.1481411172325, 1003.2861110695551, 1029.0, 18219.45341639753, 21232.0, 22082.33752987413, 22820.885895570747, 23247.418814529432, 892.3753372639178, 1007.1036777268237, 1033.235405799718, 1054.0618849224197, 1062.7608788022633, 10743.979321964402, 11372.351009103799, 11673.883261167279, 14794.63013424673, 15966.441174558835, 610303.793924119, 634792.0, 642242.0, 652769.0, 656430.0, 147093.0, 176959.0, 179881.0, 181490.64782732542, 181719.18280817286, 1095.3373209208314, 1142.2689478733616, 1171.0, 1171.3264872698232, 1175.8177482490228, 23153.42116447035, 26091.515015352205, 26206.34484137912, 26271.343216418976, 26275.817588208196, 72420.0, 74550.25449745433, 75680.0, 77332.2266777325, 80180.1981980196, 97980.51009744906, 99323.0, 99545.01819927122, 101478.4926075365, 102648.0, 12058.939705301418, 12260.816087758512, 12588.181768185405, 12685.0, 12690.17513861633, 18818.717719234497, 19273.84356938588, 19473.61052778965, 19519.8048019518, 19550.608987820448, 15998.760018599498, 16018.0, 16048.0, 16148.354065837704, 16276.755848662517, 10766.946165269124, 12363.763623637564, 13882.791758123434, 14335.92832035833, 14942.77585836235, 16254.593635159215, 16396.508104756398, 16460.176991150154, 16593.66812663764, 16661.333546658, 12250.938745306217, 12338.691532711755, 12444.191127576678, 12530.624081277576, 12618.621441356774, 15556.844431555539, 15831.762523561925, 16186.838131619004, 16235.594110146869, 16251.512454625907], "value": 12545.7199289698, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1228.9692757681057, 1465.4211586423357, 1474.6165996840825, 1474.997002038614, 1480.1341215388998, 14105.435782568698, 14602.831773458125, 14737.557873263819, 14751.409943602257, 14770.335334909929, 11769.529218831258, 14474.421023159088, 14544.345504452298, 14544.345504452298, 14596.270186490687, 2867.2152758358975, 2932.1057077591327, 2967.095036014015, 2969.2725282305864, 2970.9702902971017, 2938.7942844000927, 2939.103573410109, 2958.053422904671, 2959.1566403574984, 2961.718636729507, 2918.4892643787357, 2926.094417175475, 2932.4868148074106, 2940.059181062061, 2941.0, 2887.8556072196307, 2893.392387598596, 2903.6951120132503, 2903.70962903709, 2912.898048568311, 2741.5476446386524, 2772.7781777457753, 2775.4449110178043, 2778.70823563527, 2782.5687018512162, 14161.645958851112, 14260.215688137148, 14304.713905721826, 14326.068805527608, 14330.856691433057, 7983.2415920487465, 8179.0, 8270.421070525066, 8280.04779450359, 8282.296004839596, 4049.3116170250833, 4365.738055716635, 4987.551120399182, 5190.4550022247495, 5193.480651934814, 1331.4674130347842, 1459.6350912271932, 1487.2861026707242, 1508.2609521334577, 1509.8943073984826, 19357.806421935882, 19904.40286791384, 21605.67591486129, 21681.67477487839, 21784.782152178435, 6344.809655710336, 6906.8618627628175, 7134.2509036551455, 7162.391196748335, 7172.462065345016, 14862.702745945237, 14885.851141488663, 15007.474738384104, 15026.872984526204, 15068.095914245181, 7765.53406795594, 7773.455858089879, 7932.563708996061, 7933.167017463199, 7939.0, 7222.241664625139, 7346.63266836656, 7407.000054992614, 7452.06849143857, 7474.289942455458, 5384.461553844584, 5396.271503346998, 5504.119340905516, 5513.9448605513435, 5522.502974732293, 1584.516722399679, 1607.1642745772133, 1607.766873803292, 1626.4388785868857, 1629.380835282585, 718.9173245076884, 773.307889438949, 829.576915772948, 849.3799526345803, 863.5336918064176, 7094.326039026284, 7217.6752046157, 7439.330460258603, 7501.137369202503, 7576.24237576238, 692.7679227458858, 694.3854688600524, 715.8031541326072, 737.4616529933179, 742.076115236534, 4338.978305108518, 4947.82682606107, 5008.12357837377, 5065.696058236444, 5248.396434410015, 18852.528686782665, 19146.0, 19506.70739938902, 19605.21579136847, 19632.3128690498, 14313.069650472686, 19061.332853350064, 19113.139908704143, 19269.325573604856, 19558.022098895, 9794.16749576294, 9798.216142708567, 9859.852102218472, 9880.45657488845, 9947.701568952943, 7856.607169641609, 8007.839843202987, 8118.2693557580105, 8144.592770361577, 8453.281471075028, 17526.0, 17534.210960506553, 18045.639087218446, 18233.179506921955, 18237.45287641373, 17491.650166996842, 17900.373486927896, 18056.909715451864, 18523.44429667112, 18566.164522596246, 13009.479620815062, 13237.874780643368, 13259.0, 13267.80098298507, 13282.070255082052, 12895.48418063267, 13038.348082595643, 13169.209847409185, 13186.274754888573, 13202.339883006003, 10302.278840481094, 10586.682399528027, 10667.89332106669, 10903.0, 10908.400037997986, 10218.1825453965, 10302.22733295021, 10362.844557331797, 10637.202209834448, 10723.98122178423, 9610.855837162308, 9668.75828104303, 9702.902970970483, 9717.708468745948, 9736.561854716696, 5981.910271345846, 6000.129981152621, 6060.727267273071, 6257.998720204928, 6316.11574379578, 6379.840503987438, 6534.869302614016, 7127.679254433667, 7377.77866664001, 7380.077490313711], "value": 6356.437164361803, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.7306252779595, 464.29547883578164, 486.9926951095734, 491.38576779026215, 496.68460527564986, 5855.9121613175785, 6079.300880398756, 6277.811665650026, 6585.308542603024, 6742.258351581322, 6055.364186760382, 6059.727312270948, 6228.875422491559, 6391.296957334701, 6469.74121035158, 4053.9391909121387, 4877.365942427479, 4921.778519966592, 4935.80256789728, 4949.851504454872, 4059.9594004059877, 4219.430376899126, 4276.6792490563175, 4835.283474366602, 4846.642939976812, 4236.279832428495, 4855.757212139397, 4879.8780030499165, 4896.730679812609, 4903.9701662650705, 4724.362211101509, 4888.340074089988, 4910.705357678551, 4913.6560440769335, 4930.950690493085, 4303.687375350548, 4318.179545886303, 4318.719283246611, 4335.241332766753, 4337.089211265638, 6308.337624549402, 6311.305756366842, 6322.399372059647, 6342.175517182783, 6361.236651601791, 4911.140550403665, 5541.889162216733, 5855.8536036599, 5858.736356863951, 5876.9118463223085, 7067.469939754487, 7070.717171313193, 7083.787486375417, 7088.6455677216445, 7090.220075791711, 4944.777485013183, 4958.429780575208, 4965.900681986376, 4969.701817890938, 4983.476734942815, 7485.737999170057, 7521.5111017783665, 7543.547387156788, 7577.991832164481, 7596.063003933961, 5054.19132938728, 5056.216286475602, 5071.949280507221, 5074.213496907985, 5075.5178258065425, 4514.548545145459, 5156.381234251914, 5158.0, 5158.613104017214, 5187.948120518822, 4878.390201224847, 5849.210356601804, 5872.618279811884, 5884.558658100659, 5884.705764711833, 7115.0, 7123.928760712327, 7126.928730712731, 7141.714331426789, 7150.320719531636, 1432.2240421877007, 1504.563676533793, 1674.6901823162755, 1730.9095269979832, 1753.8509226715616, 994.786120983992, 1081.194510089988, 1098.6264670012074, 1123.3203911633507, 1151.4012713389159, 5626.943730562723, 6414.743410263537, 6533.281339052701, 6647.4349680276755, 6697.832554186184, 6940.757073502403, 7344.412447004333, 7347.265273472717, 7367.5211111277595, 7385.187629360766, 6665.633390163587, 6672.799816005528, 6688.896332105245, 6691.799246022627, 6696.866062678817, 3335.883244086494, 3454.516367708523, 3787.62123787619, 3795.5329036126736, 3810.657040866335, 7074.257202993715, 7446.478746487802, 7524.811879702942, 7566.205548417447, 7581.355584775356, 7524.9247507525315, 7536.8492630148185, 7542.396608271325, 7544.585047822423, 7629.7329593463965, 7467.551946883204, 7469.253074692596, 7496.587687677121, 7513.962430187814, 7552.8489430212185, 6855.348741869714, 7289.0, 7308.269173082649, 7313.16059068993, 7360.815979600553, 6376.93623063782, 6768.390844823989, 7054.611996340251, 7154.782017574568, 7252.2747725227055, 6299.527535434952, 6502.60984340941, 7501.4247008128905, 7595.886061708969, 7611.4291428142005, 4029.7783621901076, 4852.665516982858, 5009.373828271466, 5018.774155162953, 5027.572656324107, 6689.093608321605, 7116.644167791486, 7167.749128780468, 7305.780826575211, 7318.743843965436, 7054.153501579844, 7067.6112813795735, 7072.752453664097, 7077.256888026683, 7097.290270972861, 5883.382244864399, 7044.260352662896, 7050.224475307815, 7061.505694601534, 7090.645467726697, 5235.74342157887, 6638.734450621921, 6893.655317234218, 6920.757773477904, 6953.617551034539, 6041.607295525777, 7040.647967601702, 7075.221725610076, 7081.256468070985, 7095.503314768123, 6610.636414997029, 6775.93224067753, 6880.937982597896, 6905.447564194953, 6956.339147780953], "value": 5196.522675201583, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.5159031966299, 838.5723281126624, 843.3506200225826, 862.2283056664288, 863.0721973878083, 17008.319667213327, 17137.400190993318, 17335.39326123586, 17456.214470348834, 17462.738058929128, 12480.50077996879, 18461.815381846176, 19067.427977160674, 19071.80928190721, 19105.61788764224, 1057.434272664123, 1084.0893649334557, 1090.3675867996583, 1090.7982023325671, 1092.4210168610648, 1068.8503609494676, 1093.5571093707022, 1095.507021840171, 1096.5942601237552, 1097.8023955687972, 1116.9385683787389, 1133.8412622232895, 1147.5295128997113, 1150.120158079066, 1150.5340337163461, 1110.350444989682, 1113.704868209925, 1116.6203490813123, 1130.2992144870193, 1131.298594871181, 829.0755807274905, 838.5513750143643, 838.6896848166217, 839.215333663027, 839.9160083991613, 18238.63522729538, 18247.72628410575, 18318.90840545789, 18412.815871841245, 18426.0, 17369.565760855952, 18351.174197161024, 18475.983820889847, 18489.90755046216, 18504.722429163572, 14694.632634184123, 15028.774568381483, 15061.623459413493, 15119.017263877813, 15201.0, 3666.926661466782, 3685.5577330720216, 3689.151495156129, 3720.479132921393, 3722.3671975763914, 92467.53766231194, 127529.0, 156854.0, 158922.0, 160816.19591902083, 11523.654290371302, 11863.228890122115, 11866.288022718665, 14097.78853317201, 14145.009849310656, 25356.492870142494, 25525.61711574328, 25569.616455752806, 25696.100636477633, 25718.842652080326, 7908.485948413335, 7922.643481043367, 7934.404919631051, 7958.562279074591, 8002.799930001796, 3818.6563209311294, 3961.6632586230494, 3999.0, 4028.939565906514, 4032.3145065338963, 4805.3512775775525, 4881.755912204376, 4893.266010098514, 4919.434265059493, 4923.286123512071, 880.4937161132375, 1037.4034929915329, 1040.3393844908537, 1043.5929987304958, 1095.6274866545414, 617.274702224893, 643.9034144878212, 654.8101050695341, 676.5805200775551, 680.8765536864214, 11745.769913273747, 12099.516019359127, 12350.505979760888, 13384.464621415229, 14317.212553309668, 646.6863571167979, 661.7121552124842, 699.1156187422914, 699.576756062585, 712.74697482393, 5606.6636001840025, 6050.818475445656, 6189.721462534107, 6387.680615969183, 6477.421416942095, 118428.0, 146738.0, 147351.0, 150006.0, 151748.0, 113391.43304283425, 114981.85018149878, 118768.81231187578, 122071.0, 123034.76965230233, 101250.4937475308, 104768.47615761873, 104805.47597262118, 105608.94391056144, 108561.91438085517, 12385.318807465675, 12624.684382890502, 12758.362081896053, 12832.55086071983, 13298.202107873558, 48310.75844620755, 49069.2639610399, 49335.50664493309, 50004.24993625171, 50381.49618503768, 85660.71508927246, 88081.55959220162, 88645.11354886368, 88802.0, 98333.50833245787, 18208.90746555211, 18247.178876950304, 18302.26790928401, 18541.07294635236, 18572.721409179143, 18925.882282450682, 18962.905185473985, 19332.323368682028, 19628.214871405555, 19762.60474790525, 17874.016929069025, 18067.18697658582, 18185.545361366072, 18667.533311666775, 18734.063296834833, 17826.46520604384, 17920.372786952394, 18076.276948921895, 18120.63758724792, 18587.90706046461, 15528.689426211638, 15979.520614381123, 16016.199190040219, 16464.0, 18017.0, 11983.04135669127, 12016.939915300367, 12093.15347925672, 12143.271403715806, 12245.69385765363, 13606.183628982293, 13627.79558306646, 13675.784854000789, 14336.928315358356, 14695.92652036733], "value": 9202.423453050433, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1475.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 106.0, 112.0, 150.0, 170.0, 134.0, 102.0, 123.0, 109.0], "value": 122.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 144.0, 139.0, 110.0, 125.0, 107.0, 144.0, 142.0, 112.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 204.0, 195.0, 196.0, 193.0, 199.0, 202.0, 197.0, 200.0, 219.0], "value": 199.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 84.0, 67.0, 156.0, 58.0, 81.0, 83.0, 88.0, 120.0, 58.0], "value": 86.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 137.0, 99.0, 99.0, 88.0, 110.0, 70.0, 69.0, 107.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 88.0, 89.0, 89.0, 88.0, 88.0, 88.0, 89.0, 88.0], "value": 88.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 97.0, 95.0, 95.0, 122.0, 96.0, 96.0], "value": 98.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 91.0, 89.0, 90.0, 93.0, 93.0, 92.0, 91.0, 92.0, 90.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 59.0, 44.0, 49.0, 50.0, 51.0, 51.0, 50.0, 53.0, 51.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 53.0, 59.0, 57.0, 57.0, 58.0, 55.0, 54.0, 55.0], "value": 55.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 78.0, 77.0, 84.0, 78.0, 80.0, 80.0, 78.0, 76.0, 81.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 131.0, 128.0, 132.0, 134.0, 147.0, 140.0, 150.0, 158.0], "value": 141.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 179.0, 175.0, 174.0, 173.0, 173.0, 175.0, 177.0, 180.0, 172.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 66.0, 62.0, 63.0, 63.0, 65.0, 64.0, 64.0, 65.0], "value": 64.1, "unit": "score"}]}]} 08:56:24 INFO - Return code: 0 08:56:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:56:24 INFO - # TBPL SUCCESS # 08:56:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:56:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:56:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:56:24 INFO - 2017-06-13 08:56:24.977 screenresolution[1666:11258] starting screenresolution argv=screenresolution get 08:56:24 INFO - 2017-06-13 08:56:24.979 screenresolution[1666:11258] Display 0: 1600x1200x32@60 08:56:24 INFO - 2017-06-13 08:56:24.985 screenresolution[1667:11261] starting screenresolution argv=screenresolution list 08:56:24 INFO - Available Modes on Display 0 08:56:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:56:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:56:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:56:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:56:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:56:25 INFO - Intel Iris: 08:56:25 INFO - Chipset Model: Intel Iris 08:56:25 INFO - Type: GPU 08:56:25 INFO - Bus: Built-In 08:56:25 INFO - VRAM (Dynamic, Max): 1536 MB 08:56:25 INFO - Vendor: Intel (0x8086) 08:56:25 INFO - Device ID: 0x0a2e 08:56:25 INFO - Revision ID: 0x0009 08:56:25 INFO - Displays: 08:56:25 INFO - Display: 08:56:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:56:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:56:25 INFO - Display Serial Number: 1600x1200 60 08:56:25 INFO - Main Display: Yes 08:56:25 INFO - Mirror: Off 08:56:25 INFO - Online: Yes 08:56:25 INFO - Rotation: Supported 08:56:25 INFO - Return code: 0 08:56:25 INFO - Running post-action listener: _resource_record_post_action 08:56:25 INFO - [mozharness: 2017-06-13 15:56:25.229996Z] Finished run-tests step (success) 08:56:25 INFO - Running post-run listener: _resource_record_post_run 08:56:25 INFO - Total resource usage - Wall time: 1246s; CPU: 53.0%; Read bytes: 40915968; Write bytes: 474165248; Read time: 275; Write time: 1552 08:56:25 INFO - TinderboxPrint: CPU usage
53.0% 08:56:25 INFO - TinderboxPrint: I/O read bytes / time
40,915,968 / 275 08:56:25 INFO - TinderboxPrint: I/O write bytes / time
474,165,248 / 1,552 08:56:25 INFO - TinderboxPrint: CPU idle
2,345.0 (47.0%) 08:56:25 INFO - TinderboxPrint: CPU system
1,005.6 (20.2%) 08:56:25 INFO - TinderboxPrint: CPU user
1,635.0 (32.8%) 08:56:25 INFO - TinderboxPrint: Swap in / out
194,797,568 / 0 08:56:25 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 154675712; Write bytes: 158386176; Read time: 12158; Write time: 353 08:56:25 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:56:25 INFO - run-tests - Wall time: 1231s; CPU: 53.0%; Read bytes: 36594688; Write bytes: 314378240; Read time: 247; Write time: 1195 08:56:25 INFO - Running post-run listener: _upload_blobber_files 08:56:25 INFO - Blob upload gear active. 08:56:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:56:25 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 08:56:25 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'] 08:56:25 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 08:56:26 INFO - (blobuploader) - INFO - Open directory for files ... 08:56:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:56:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:56:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:56:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:56:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:56:27 INFO - (blobuploader) - INFO - Done attempting. 08:56:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:56:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:56:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:56:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:56:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:56:28 INFO - (blobuploader) - INFO - Done attempting. 08:56:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:56:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:56:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:56:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:56:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:56:29 INFO - (blobuploader) - INFO - Done attempting. 08:56:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:56:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:56:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:56:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:56:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:56:31 INFO - (blobuploader) - INFO - Done attempting. 08:56:31 INFO - (blobuploader) - INFO - Iteration through files over. 08:56:31 INFO - Return code: 0 08:56:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:56:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:56:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b51adfee4d367841be40226291fe6db0c8c1e94359f8f929def96c37a16f06c5369ad92d3b5d85ee7dd75dd7ccd21d23432e83702950b01efb14c55a9ff9b18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e1277f1b9b7c87d901593fb4ca2b340e3639ac0262522ed6e233cafb159bb82ad9cc05783f3e693f083aba7dbc846a1db415a0798fa550981f66804179090b53", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3ec9849211c505629d6522fa00341a0dba4b2ead9a03343317997f5faa541d9134266fe159337626e33e0353e231eaddce7ac8a99b0838995273b0635616b22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1befed35f04472872f55f78409d46d154d161edf8f1f0998dae1915a903afa5a0560f38b74ca850b56f16cea91e4164fd71970f6f02f43221c66efc61225ef34"} 08:56:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:56:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:56:31 INFO - Contents: 08:56:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b51adfee4d367841be40226291fe6db0c8c1e94359f8f929def96c37a16f06c5369ad92d3b5d85ee7dd75dd7ccd21d23432e83702950b01efb14c55a9ff9b18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e1277f1b9b7c87d901593fb4ca2b340e3639ac0262522ed6e233cafb159bb82ad9cc05783f3e693f083aba7dbc846a1db415a0798fa550981f66804179090b53", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3ec9849211c505629d6522fa00341a0dba4b2ead9a03343317997f5faa541d9134266fe159337626e33e0353e231eaddce7ac8a99b0838995273b0635616b22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1befed35f04472872f55f78409d46d154d161edf8f1f0998dae1915a903afa5a0560f38b74ca850b56f16cea91e4164fd71970f6f02f43221c66efc61225ef34"} 08:56:31 INFO - Running post-run listener: copy_logs_to_upload_dir 08:56:31 INFO - Copying logs to upload dir... 08:56:31 INFO - mkdir: /builds/slave/test/build/upload/logs 08:56:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1328.586844 ========= master_lag: 0.58 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 9 secs) (at 2017-06-13 08:56:31.772887) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 08:56:31.777456) ========= 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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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/7b51adfee4d367841be40226291fe6db0c8c1e94359f8f929def96c37a16f06c5369ad92d3b5d85ee7dd75dd7ccd21d23432e83702950b01efb14c55a9ff9b18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e1277f1b9b7c87d901593fb4ca2b340e3639ac0262522ed6e233cafb159bb82ad9cc05783f3e693f083aba7dbc846a1db415a0798fa550981f66804179090b53", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3ec9849211c505629d6522fa00341a0dba4b2ead9a03343317997f5faa541d9134266fe159337626e33e0353e231eaddce7ac8a99b0838995273b0635616b22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1befed35f04472872f55f78409d46d154d161edf8f1f0998dae1915a903afa5a0560f38b74ca850b56f16cea91e4164fd71970f6f02f43221c66efc61225ef34"} build_url:https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010565 build_url: 'https://queue.taskcluster.net/v1/task/ezV1EGOcRDeW7fz1QtaKaA/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/7b51adfee4d367841be40226291fe6db0c8c1e94359f8f929def96c37a16f06c5369ad92d3b5d85ee7dd75dd7ccd21d23432e83702950b01efb14c55a9ff9b18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e1277f1b9b7c87d901593fb4ca2b340e3639ac0262522ed6e233cafb159bb82ad9cc05783f3e693f083aba7dbc846a1db415a0798fa550981f66804179090b53", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3ec9849211c505629d6522fa00341a0dba4b2ead9a03343317997f5faa541d9134266fe159337626e33e0353e231eaddce7ac8a99b0838995273b0635616b22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1befed35f04472872f55f78409d46d154d161edf8f1f0998dae1915a903afa5a0560f38b74ca850b56f16cea91e4164fd71970f6f02f43221c66efc61225ef34"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 08:56:31.813684) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 08:56:31.814040) ========= 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.Hpq3qqdIu7/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.ndLLBMP4SO/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.005161 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 08:56:31.863186) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 08:56:31.863545) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 08:56:31.863876) ========= ========= Total master_lag: 0.72 =========