builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0205 starttime: 1497463329.12 results: success (0) buildid: 20170614093034 builduid: 8b14893e40b74f3197bf6230d39430cc revision: 21965d67c5fe68e82b9c3d2a04ba128baf2bd589 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 11:02:09.122703) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 11:02:09.123272) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 11:02:09.123695) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 11:02:09.148619) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 11:02:09.148992) ========= 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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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-14 11:02:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 63.8M=0s 2017-06-14 11:02:09 (63.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128952 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 11:02:09.301434) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 11:02:09.301972) ========= 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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.027132 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 11:02:09.351765) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-06-14 11:02:09.352112) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 21965d67c5fe68e82b9c3d2a04ba128baf2bd589 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 21965d67c5fe68e82b9c3d2a04ba128baf2bd589 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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-14 11:02:09,461 truncating revision to first 12 chars 2017-06-14 11:02:09,461 Setting DEBUG logging. 2017-06-14 11:02:09,461 attempt 1/10 2017-06-14 11:02:09,461 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/21965d67c5fe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-14 11:02:11,582 unpacking tar archive at: mozilla-beta-21965d67c5fe/testing/mozharness/ program finished with exit code 0 elapsedTime=2.513995 ========= master_lag: 0.58 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-06-14 11:02:12.443165) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 11:02:12.443530) ========= script_repo_revision: 21965d67c5fe68e82b9c3d2a04ba128baf2bd589 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 11:02:12.443972) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 11:02:12.444236) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 11:02:12.463345) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2017-06-14 11:02:12.463735) ========= /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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:02:12 INFO - MultiFileLogger online at 20170614 11:02:12 in /builds/slave/test 11:02:12 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 11:02:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:02:12 INFO - {'append_to_log': False, 11:02:12 INFO - 'base_work_dir': '/builds/slave/test', 11:02:12 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:02:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:02:12 INFO - 'branch': 'Mozilla-Beta', 11:02:12 INFO - 'buildbot_json_path': 'buildprops.json', 11:02:12 INFO - 'config_files': ('talos/mac_config.py',), 11:02:12 INFO - 'default_actions': ('clobber', 11:02:12 INFO - 'read-buildbot-config', 11:02:12 INFO - 'download-and-extract', 11:02:12 INFO - 'populate-webroot', 11:02:12 INFO - 'create-virtualenv', 11:02:12 INFO - 'install', 11:02:12 INFO - 'setup-mitmproxy', 11:02:12 INFO - 'run-tests'), 11:02:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:02:12 INFO - 'download_minidump_stackwalk': True, 11:02:12 INFO - 'download_symbols': 'ondemand', 11:02:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:02:12 INFO - 'tooltool.py': '/tools/tooltool.py', 11:02:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:02:12 INFO - '/tools/misc-python/virtualenv.py')}, 11:02:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:02:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:02:12 INFO - 'gecko_profile': False, 11:02:12 INFO - 'gecko_profile_interval': 0, 11:02:12 INFO - 'installer_path': 'installer.exe', 11:02:12 INFO - 'log_level': 'info', 11:02:12 INFO - 'log_name': 'talos', 11:02:12 INFO - 'log_to_console': True, 11:02:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:02:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:02:12 INFO - 'opt_config_files': (), 11:02:12 INFO - 'pip_index': False, 11:02:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:02:12 INFO - 'cmd': ('bash', 11:02:12 INFO - '-c', 11:02:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:02:12 INFO - 'enabled': True, 11:02:12 INFO - 'halt_on_failure': False, 11:02:12 INFO - 'name': 'check_screen_resolution'},), 11:02:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:02:12 INFO - 'cmd': ('bash', 11:02:12 INFO - '-c', 11:02:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:02:12 INFO - 'enabled': True, 11:02:12 INFO - 'halt_on_failure': False, 11:02:12 INFO - 'name': 'check_screen_resolution'},), 11:02:12 INFO - 'run_cmd_checks_enabled': True, 11:02:12 INFO - 'suite': 'dromaeojs-e10s', 11:02:12 INFO - 'system_bits': '32', 11:02:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:02:12 INFO - 'title': 't-yosemite-r7-0205', 11:02:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:02:12 INFO - 'use_talos_json': True, 11:02:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:02:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:02:12 INFO - 'work_dir': 'build'} 11:02:12 INFO - [mozharness: 2017-06-14 18:02:12.683463Z] Running clobber step. 11:02:12 INFO - Running pre-action listener: _resource_record_pre_action 11:02:12 INFO - Running main action method: clobber 11:02:12 INFO - rmtree: /builds/slave/test/build 11:02:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:02:15 INFO - Running post-action listener: _resource_record_post_action 11:02:15 INFO - [mozharness: 2017-06-14 18:02:15.094766Z] Finished clobber step (success) 11:02:15 INFO - [mozharness: 2017-06-14 18:02:15.094921Z] Running read-buildbot-config step. 11:02:15 INFO - Running pre-action listener: _resource_record_pre_action 11:02:15 INFO - Running main action method: read_buildbot_config 11:02:15 INFO - Using buildbot properties: 11:02:15 INFO - { 11:02:15 INFO - "project": "", 11:02:15 INFO - "product": "firefox", 11:02:15 INFO - "script_repo_revision": "production", 11:02:15 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 11:02:15 INFO - "repository": "", 11:02:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 11:02:15 INFO - "buildid": "20170614093034", 11:02:15 INFO - "pgo_build": "False", 11:02:15 INFO - "basedir": "/builds/slave/test", 11:02:15 INFO - "buildnumber": 5, 11:02:15 INFO - "slavename": "t-yosemite-r7-0205", 11:02:15 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 11:02:15 INFO - "platform": "yosemite_r7_devedition", 11:02:15 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 11:02:15 INFO - "branch": "mozilla-beta", 11:02:15 INFO - "repo_path": "releases/mozilla-beta", 11:02:15 INFO - "revision": "21965d67c5fe68e82b9c3d2a04ba128baf2bd589", 11:02:15 INFO - "stage_platform": "macosx64-devedition", 11:02:15 INFO - "builduid": "8b14893e40b74f3197bf6230d39430cc", 11:02:15 INFO - "slavebuilddir": "test" 11:02:15 INFO - } 11:02:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 11:02:15 INFO - Running post-action listener: _resource_record_post_action 11:02:15 INFO - [mozharness: 2017-06-14 18:02:15.098115Z] Finished read-buildbot-config step (success) 11:02:15 INFO - [mozharness: 2017-06-14 18:02:15.098221Z] Running download-and-extract step. 11:02:15 INFO - Running pre-action listener: _resource_record_pre_action 11:02:15 INFO - Running main action method: download_and_extract 11:02:15 INFO - mkdir: /builds/slave/test/build/tests 11:02:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:15 INFO - trying https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 11:02:15 INFO - Downloading https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/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 11:02:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/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 11:02:16 INFO - Downloaded 1290 bytes. 11:02:16 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 11:02:16 INFO - Using the following test package requirements: 11:02:16 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:02:16 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 11:02:16 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 11:02:16 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:02:16 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 11:02:16 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:02:16 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 11:02:16 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 11:02:16 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:02:16 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 11:02:16 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 11:02:16 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:02:16 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 11:02:16 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:02:16 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 11:02:16 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:02:16 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 11:02:16 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:02:16 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 11:02:16 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 11:02:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 11:02:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 11:02:16 INFO - Fetch https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 11:02:18 INFO - Content-Length response header: 59545205 11:02:18 INFO - Bytes received: 59545205 11:02:36 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 11:02:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 11:02:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 11:02:36 INFO - Fetch https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 11:02:38 INFO - Content-Length response header: 59545205 11:02:38 INFO - Bytes received: 59545205 11:02:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 11:02:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 11:02:55 INFO - Fetch https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 11:02:56 INFO - Content-Length response header: 14450018 11:02:56 INFO - Bytes received: 14450018 11:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:57 INFO - trying https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 11:02:57 INFO - Downloading https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 11:02:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/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 11:02:59 INFO - Downloaded 66877355 bytes. 11:02:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 11:02:59 INFO - mkdir: /builds/slave/test/properties 11:02:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:02:59 INFO - Writing to file /builds/slave/test/properties/build_url 11:02:59 INFO - Contents: 11:02:59 INFO - build_url:https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 11:03:00 INFO - Running post-action listener: _resource_record_post_action 11:03:00 INFO - Running post-action listener: set_extra_try_arguments 11:03:00 INFO - [mozharness: 2017-06-14 18:03:00.292767Z] Finished download-and-extract step (success) 11:03:00 INFO - [mozharness: 2017-06-14 18:03:00.292901Z] Running populate-webroot step. 11:03:00 INFO - Running pre-action listener: _resource_record_pre_action 11:03:00 INFO - Running main action method: populate_webroot 11:03:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:03:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:03:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:03:00 INFO - '--tppagecycles', 11:03:00 INFO - '7'], 11:03:00 INFO - 'tests': ['tsvgm']}}, 11:03:00 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:03:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:03:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:03:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:03:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:03:00 INFO - 'talos_options': ['--disable-e10s'], 11:03:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:03:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:03:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:03:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:03:00 INFO - 'talos_options': ['--disable-e10s'], 11:03:00 INFO - 'tests': ['damp', 'tps']}, 11:03:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:03:00 INFO - 'tests': ['damp', 'tps']}, 11:03:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:03:00 INFO - 'tests': ['dromaeo_dom']}, 11:03:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:03:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:03:00 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:03:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:03:00 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:03:00 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:03:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:03:00 INFO - 'tests': ['a11yr', 11:03:00 INFO - 'ts_paint', 11:03:00 INFO - 'tpaint', 11:03:00 INFO - 'sessionrestore', 11:03:00 INFO - 'sessionrestore_no_auto_restore', 11:03:00 INFO - 'tabpaint']}, 11:03:00 INFO - 'other-e10s': {'tests': ['a11yr', 11:03:00 INFO - 'ts_paint', 11:03:00 INFO - 'tpaint', 11:03:00 INFO - 'sessionrestore', 11:03:00 INFO - 'sessionrestore_no_auto_restore', 11:03:00 INFO - 'tabpaint']}, 11:03:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:03:00 INFO - 'ts_paint', 11:03:00 INFO - 'tpaint', 11:03:00 INFO - 'sessionrestore', 11:03:00 INFO - 'sessionrestore_no_auto_restore', 11:03:00 INFO - 'tabpaint']}, 11:03:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:03:00 INFO - 'ts_paint', 11:03:00 INFO - 'tpaint', 11:03:00 INFO - 'sessionrestore', 11:03:00 INFO - 'sessionrestore_no_auto_restore', 11:03:00 INFO - 'tabpaint']}, 11:03:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:03:00 INFO - 'tests': ['a11yr', 11:03:00 INFO - 'ts_paint', 11:03:00 INFO - 'tpaint', 11:03:00 INFO - 'sessionrestore', 11:03:00 INFO - 'sessionrestore_no_auto_restore', 11:03:00 INFO - 'tabpaint']}, 11:03:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:03:00 INFO - 'tests': ['a11yr', 11:03:00 INFO - 'ts_paint', 11:03:00 INFO - 'tpaint', 11:03:00 INFO - 'sessionrestore', 11:03:00 INFO - 'sessionrestore_no_auto_restore', 11:03:00 INFO - 'tabpaint']}, 11:03:00 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 11:03:00 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 11:03:00 INFO - 'bloom_basic_ref']}, 11:03:00 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:03:00 INFO - 'talos_options': ['--mitmproxy', 11:03:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:03:00 INFO - '--firstNonBlankPaint'], 11:03:00 INFO - 'tests': ['quantum_pageload_google', 11:03:00 INFO - 'quantum_pageload_youtube', 11:03:00 INFO - 'quantum_pageload_amazon', 11:03:00 INFO - 'quantum_pageload_facebook']}, 11:03:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:03:00 INFO - 'tests': ['tsvgx', 11:03:00 INFO - 'tsvgr_opacity', 11:03:00 INFO - 'tart', 11:03:00 INFO - 'tscrollx', 11:03:00 INFO - 'cart', 11:03:00 INFO - 'tsvg_static']}, 11:03:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:03:00 INFO - 'tsvgr_opacity', 11:03:00 INFO - 'tart', 11:03:00 INFO - 'tscrollx', 11:03:00 INFO - 'cart', 11:03:00 INFO - 'tsvg_static']}, 11:03:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:03:00 INFO - 'talos_options': ['--disable-e10s'], 11:03:00 INFO - 'tests': ['tp5o']}, 11:03:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:03:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:03:00 INFO - 'talos_options': ['--disable-e10s', 11:03:00 INFO - '--xperf_path', 11:03:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:03:00 INFO - 'tests': ['tp5n']}, 11:03:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:03:00 INFO - 'talos_options': ['--xperf_path', 11:03:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:03:00 INFO - 'tests': ['tp5n']}}, 11:03:00 INFO - 'talos.zip': {'path': '', 11:03:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:03:00 INFO - Running post-action listener: _resource_record_post_action 11:03:00 INFO - [mozharness: 2017-06-14 18:03:00.314233Z] Finished populate-webroot step (success) 11:03:00 INFO - [mozharness: 2017-06-14 18:03:00.314347Z] Running create-virtualenv step. 11:03:00 INFO - Running pre-action listener: _resource_record_pre_action 11:03:00 INFO - Running main action method: create_virtualenv 11:03:00 INFO - Creating virtualenv /builds/slave/test/build/venv 11:03:00 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:03:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:03:00 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:03:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:03:00 INFO - Using real prefix '/tools/python27' 11:03:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:03:01 INFO - Installing distribute.............................................................................................................................................................................................done. 11:03:04 INFO - Installing pip.................done. 11:03:04 INFO - Return code: 0 11:03:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:03:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:03:04 INFO - Reading from file tmpfile_stdout 11:03:04 INFO - Output received: 11:03:04 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 11:03:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d661c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d665768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2c856df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b7d30>, '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 0x10d381cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3a8be8>, '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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:03:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:03:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:03:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jQ5fQRZYTP/Render', 11:03:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:04 INFO - 'HOME': '/Users/cltbld', 11:03:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:04 INFO - 'LOGNAME': 'cltbld', 11:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:04 INFO - 'MOZ_NO_REMOTE': '1', 11:03:04 INFO - 'NO_EM_RESTART': '1', 11:03:04 INFO - 'PAGER': '/bin/cat', 11:03:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:04 INFO - 'PWD': '/builds/slave/test', 11:03:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:04 INFO - 'SHELL': '/bin/bash', 11:03:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXHxoqjvFs/Listeners', 11:03:04 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 11:03:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:04 INFO - 'USER': 'cltbld', 11:03:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:04 INFO - 'XPC_FLAGS': '0x0', 11:03:04 INFO - 'XPC_SERVICE_NAME': '0', 11:03:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:03:04 INFO - Cleaning up... 11:03:04 INFO - Return code: 0 11:03:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d661c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d665768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2c856df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b7d30>, '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 0x10d381cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3a8be8>, '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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:03:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:03:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:03:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jQ5fQRZYTP/Render', 11:03:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:04 INFO - 'HOME': '/Users/cltbld', 11:03:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:04 INFO - 'LOGNAME': 'cltbld', 11:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:04 INFO - 'MOZ_NO_REMOTE': '1', 11:03:04 INFO - 'NO_EM_RESTART': '1', 11:03:04 INFO - 'PAGER': '/bin/cat', 11:03:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:04 INFO - 'PWD': '/builds/slave/test', 11:03:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:04 INFO - 'SHELL': '/bin/bash', 11:03:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXHxoqjvFs/Listeners', 11:03:04 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 11:03:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:04 INFO - 'USER': 'cltbld', 11:03:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:04 INFO - 'XPC_FLAGS': '0x0', 11:03:04 INFO - 'XPC_SERVICE_NAME': '0', 11:03:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:04 INFO - Downloading/unpacking psutil>=3.1.1 11:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:03:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:03:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:03:06 INFO - Installing collected packages: psutil 11:03:06 INFO - Running setup.py install for psutil 11:03:06 INFO - building 'psutil._psutil_osx' extension 11:03:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:03:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:03:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:03:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:03:07 INFO - building 'psutil._psutil_posix' extension 11:03:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:03:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:03:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:03:07 INFO - ^ 11:03:07 INFO - 1 warning generated. 11:03:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:03:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:03:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:03:07 INFO - Successfully installed psutil 11:03:07 INFO - Cleaning up... 11:03:07 INFO - Return code: 0 11:03:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:03:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d661c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d665768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2c856df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b7d30>, '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 0x10d381cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3a8be8>, '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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:03:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:03:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jQ5fQRZYTP/Render', 11:03:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:08 INFO - 'HOME': '/Users/cltbld', 11:03:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:08 INFO - 'LOGNAME': 'cltbld', 11:03:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:08 INFO - 'MOZ_NO_REMOTE': '1', 11:03:08 INFO - 'NO_EM_RESTART': '1', 11:03:08 INFO - 'PAGER': '/bin/cat', 11:03:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:08 INFO - 'PWD': '/builds/slave/test', 11:03:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:08 INFO - 'SHELL': '/bin/bash', 11:03:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXHxoqjvFs/Listeners', 11:03:08 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 11:03:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:08 INFO - 'USER': 'cltbld', 11:03:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:08 INFO - 'XPC_FLAGS': '0x0', 11:03:08 INFO - 'XPC_SERVICE_NAME': '0', 11:03:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:03:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:03:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:03:10 INFO - Installing collected packages: mozsystemmonitor 11:03:10 INFO - Running setup.py install for mozsystemmonitor 11:03:10 INFO - Successfully installed mozsystemmonitor 11:03:10 INFO - Cleaning up... 11:03:10 INFO - Return code: 0 11:03:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:03:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:10 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 0x10d661c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d665768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2c856df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b7d30>, '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 0x10d381cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3a8be8>, '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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:03:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:03:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:03:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jQ5fQRZYTP/Render', 11:03:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:10 INFO - 'HOME': '/Users/cltbld', 11:03:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:10 INFO - 'LOGNAME': 'cltbld', 11:03:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:10 INFO - 'MOZ_NO_REMOTE': '1', 11:03:10 INFO - 'NO_EM_RESTART': '1', 11:03:10 INFO - 'PAGER': '/bin/cat', 11:03:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:10 INFO - 'PWD': '/builds/slave/test', 11:03:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:10 INFO - 'SHELL': '/bin/bash', 11:03:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXHxoqjvFs/Listeners', 11:03:10 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 11:03:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:10 INFO - 'USER': 'cltbld', 11:03:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:10 INFO - 'XPC_FLAGS': '0x0', 11:03:10 INFO - 'XPC_SERVICE_NAME': '0', 11:03:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:11 INFO - Downloading/unpacking jsonschema==2.5.1 11:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:03:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:12 INFO - Downloading functools32-3.2.3-2.tar.gz 11:03:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:03:12 INFO - warning: no files found matching '*.txt' 11:03:12 INFO - no previously-included directories found matching 'build' 11:03:12 INFO - no previously-included directories found matching 'dist' 11:03:12 INFO - no previously-included directories found matching '.git*' 11:03:12 INFO - Installing collected packages: jsonschema, functools32 11:03:12 INFO - Running setup.py install for functools32 11:03:12 INFO - warning: no files found matching '*.txt' 11:03:12 INFO - no previously-included directories found matching 'build' 11:03:12 INFO - no previously-included directories found matching 'dist' 11:03:12 INFO - no previously-included directories found matching '.git*' 11:03:12 INFO - Successfully installed jsonschema functools32 11:03:12 INFO - Cleaning up... 11:03:13 INFO - Return code: 0 11:03:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:03:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:13 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 0x10d661c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d665768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2c856df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b7d30>, '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 0x10d381cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3a8be8>, '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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:03:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:03:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:03:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jQ5fQRZYTP/Render', 11:03:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:13 INFO - 'HOME': '/Users/cltbld', 11:03:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:13 INFO - 'LOGNAME': 'cltbld', 11:03:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:13 INFO - 'MOZ_NO_REMOTE': '1', 11:03:13 INFO - 'NO_EM_RESTART': '1', 11:03:13 INFO - 'PAGER': '/bin/cat', 11:03:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:13 INFO - 'PWD': '/builds/slave/test', 11:03:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:13 INFO - 'SHELL': '/bin/bash', 11:03:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXHxoqjvFs/Listeners', 11:03:13 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 11:03:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:13 INFO - 'USER': 'cltbld', 11:03:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:13 INFO - 'XPC_FLAGS': '0x0', 11:03:13 INFO - 'XPC_SERVICE_NAME': '0', 11:03:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:03:13 INFO - Cleaning up... 11:03:13 INFO - Return code: 0 11:03:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:03:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:13 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 0x10d661c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d665768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2c856df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b7d30>, '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 0x10d381cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3a8be8>, '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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:03:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:03:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:03:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jQ5fQRZYTP/Render', 11:03:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:13 INFO - 'HOME': '/Users/cltbld', 11:03:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:13 INFO - 'LOGNAME': 'cltbld', 11:03:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:13 INFO - 'MOZ_NO_REMOTE': '1', 11:03:13 INFO - 'NO_EM_RESTART': '1', 11:03:13 INFO - 'PAGER': '/bin/cat', 11:03:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:13 INFO - 'PWD': '/builds/slave/test', 11:03:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:13 INFO - 'SHELL': '/bin/bash', 11:03:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXHxoqjvFs/Listeners', 11:03:13 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 11:03:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:13 INFO - 'USER': 'cltbld', 11:03:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:13 INFO - 'XPC_FLAGS': '0x0', 11:03:13 INFO - 'XPC_SERVICE_NAME': '0', 11:03:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:13 INFO - Downloading/unpacking blobuploader==1.2.4 11:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:14 INFO - Downloading blobuploader-1.2.4.tar.gz 11:03:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:03:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:03: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 11:03: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 11:03: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 11:03: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 11:03:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:03:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:03: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 11:03: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 11:03: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 11:03: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 11:03:15 INFO - Downloading docopt-0.6.1.tar.gz 11:03:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:03:15 INFO - Installing collected packages: blobuploader, requests, docopt 11:03:15 INFO - Running setup.py install for blobuploader 11:03:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:03:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:03:16 INFO - Running setup.py install for requests 11:03:16 INFO - Running setup.py install for docopt 11:03:16 INFO - Successfully installed blobuploader requests docopt 11:03:16 INFO - Cleaning up... 11:03:16 INFO - Return code: 0 11:03:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:03:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:16 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 0x10d661c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d665768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2c856df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b7d30>, '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 0x10d381cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3a8be8>, '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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:03:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:03:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:03:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jQ5fQRZYTP/Render', 11:03:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:16 INFO - 'HOME': '/Users/cltbld', 11:03:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:16 INFO - 'LOGNAME': 'cltbld', 11:03:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:16 INFO - 'MOZ_NO_REMOTE': '1', 11:03:16 INFO - 'NO_EM_RESTART': '1', 11:03:16 INFO - 'PAGER': '/bin/cat', 11:03:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:16 INFO - 'PWD': '/builds/slave/test', 11:03:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:16 INFO - 'SHELL': '/bin/bash', 11:03:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXHxoqjvFs/Listeners', 11:03:16 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 11:03:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:16 INFO - 'USER': 'cltbld', 11:03:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:16 INFO - 'XPC_FLAGS': '0x0', 11:03:16 INFO - 'XPC_SERVICE_NAME': '0', 11:03:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:03:17 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-LdKUpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:03:17 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-IE05uq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:03:17 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-w7b1BK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:03:17 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-tNeRRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:03:17 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-xHvUjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:03:17 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-_FAa_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:03:17 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-q06mf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:03:17 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-9mupQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:03:17 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-779vkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:03:17 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-rFnRkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:03:18 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-5TsFnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:03:18 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-n12v1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:03:18 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-zxvYbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:03:18 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-R8JR0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:18 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-HngIsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:03:18 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-F4Ril8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:03:18 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-oTk1rW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:03:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:03:18 INFO - Running setup.py install for manifestparser 11:03:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:03:18 INFO - Running setup.py install for mozcrash 11:03:19 INFO - Running setup.py install for mozdebug 11:03:19 INFO - Running setup.py install for mozdevice 11:03:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:03:19 INFO - Running setup.py install for mozfile 11:03:19 INFO - Running setup.py install for mozhttpd 11:03:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:03:19 INFO - Running setup.py install for mozinfo 11:03:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:03:19 INFO - Running setup.py install for mozInstall 11:03:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:03:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:03:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:03:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:03:19 INFO - Running setup.py install for mozleak 11:03:20 INFO - Running setup.py install for mozlog 11:03:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:03:20 INFO - Running setup.py install for moznetwork 11:03:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:03:20 INFO - Running setup.py install for mozprocess 11:03:20 INFO - Running setup.py install for mozprofile 11:03:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:03:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:03:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:03:20 INFO - Running setup.py install for mozrunner 11:03:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:03:20 INFO - Running setup.py install for mozscreenshot 11:03:21 INFO - Running setup.py install for moztest 11:03:21 INFO - Running setup.py install for mozversion 11:03:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:03:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:03:21 INFO - Cleaning up... 11:03:21 INFO - Return code: 0 11:03:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:03: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')]} 11:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03: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')]} 11:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:21 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 0x10d661c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d665768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2c856df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b7d30>, '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 0x10d381cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3a8be8>, '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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:03:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:03:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jQ5fQRZYTP/Render', 11:03:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:21 INFO - 'HOME': '/Users/cltbld', 11:03:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:21 INFO - 'LOGNAME': 'cltbld', 11:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:21 INFO - 'MOZ_NO_REMOTE': '1', 11:03:21 INFO - 'NO_EM_RESTART': '1', 11:03:21 INFO - 'PAGER': '/bin/cat', 11:03:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:21 INFO - 'PWD': '/builds/slave/test', 11:03:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:21 INFO - 'SHELL': '/bin/bash', 11:03:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXHxoqjvFs/Listeners', 11:03:21 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 11:03:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:21 INFO - 'USER': 'cltbld', 11:03:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:21 INFO - 'XPC_FLAGS': '0x0', 11:03:21 INFO - 'XPC_SERVICE_NAME': '0', 11:03:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:03:21 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-ZbvX6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:03:21 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-2k4QNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:03:21 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-woPWKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:03:21 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-dGMP0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:03:22 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)) 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:03:22 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-ne0knz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:03:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:03:22 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-C7CnB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:03:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:03:22 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Sbqy70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:03:22 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)) 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:03:22 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-N7Qx_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:03:22 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)) 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:03:22 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-2yh8F2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:03:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:03:22 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-hTTRc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:03:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:03:22 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-yIRex2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:03:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:03:22 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-RVTfvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:03:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:03:22 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-y0M2sz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:03:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:03:23 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-dVhpoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:03:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:23 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-nqBDhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:03:23 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-LJJP66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:03:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:03:23 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-sRAdl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:03:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:03:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:03:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:03:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:03:23 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)) 11:03:23 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)) 11:03:23 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)) 11:03:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:03:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:24 INFO - Downloading blessings-1.6.tar.gz 11:03:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:03:24 INFO - Installing collected packages: blessings 11:03:24 INFO - Running setup.py install for blessings 11:03:25 INFO - Successfully installed blessings 11:03:25 INFO - Cleaning up... 11:03:25 INFO - Return code: 0 11:03:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:03:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:03:25 INFO - Reading from file tmpfile_stdout 11:03:25 INFO - Current package versions: 11:03:25 INFO - blessings == 1.6 11:03:25 INFO - blobuploader == 1.2.4 11:03:25 INFO - docopt == 0.6.1 11:03:25 INFO - functools32 == 3.2.3-2 11:03:25 INFO - jsonschema == 2.5.1 11:03:25 INFO - manifestparser == 1.1 11:03:25 INFO - mozInstall == 1.13 11:03:25 INFO - mozcrash == 1.0 11:03:25 INFO - mozdebug == 0.1 11:03:25 INFO - mozdevice == 0.51 11:03:25 INFO - mozfile == 1.2 11:03:25 INFO - mozhttpd == 0.7 11:03:25 INFO - mozinfo == 0.10 11:03:25 INFO - mozleak == 0.1 11:03:25 INFO - mozlog == 3.4 11:03:25 INFO - moznetwork == 0.27 11:03:25 INFO - mozprocess == 0.25 11:03:25 INFO - mozprofile == 0.28 11:03:25 INFO - mozrunner == 6.13 11:03:25 INFO - mozscreenshot == 0.1 11:03:25 INFO - mozsystemmonitor == 0.3 11:03:25 INFO - moztest == 0.8 11:03:25 INFO - mozversion == 1.4 11:03:25 INFO - psutil == 3.1.1 11:03:25 INFO - requests == 1.2.3 11:03:25 INFO - wsgiref == 0.1.2 11:03:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:03:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:25 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 0x10d661c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d665768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2c856df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b7d30>, '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 0x10d381cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3a8be8>, '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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:03:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:03:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jQ5fQRZYTP/Render', 11:03:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:25 INFO - 'HOME': '/Users/cltbld', 11:03:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:25 INFO - 'LOGNAME': 'cltbld', 11:03:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:25 INFO - 'MOZ_NO_REMOTE': '1', 11:03:25 INFO - 'NO_EM_RESTART': '1', 11:03:25 INFO - 'PAGER': '/bin/cat', 11:03:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:25 INFO - 'PWD': '/builds/slave/test', 11:03:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:25 INFO - 'SHELL': '/bin/bash', 11:03:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXHxoqjvFs/Listeners', 11:03:25 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 11:03:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:25 INFO - 'USER': 'cltbld', 11:03:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:25 INFO - 'XPC_FLAGS': '0x0', 11:03:25 INFO - 'XPC_SERVICE_NAME': '0', 11:03:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:03:25 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:03: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 11:03: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 11:03: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 11:03: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 11:03:27 INFO - Downloading marionette_driver-1.4.0.tar.gz 11:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 11:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:03:27 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)) 11:03:27 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)) 11:03:27 INFO - Installing collected packages: marionette-driver 11:03:27 INFO - Running setup.py install for marionette-driver 11:03:27 INFO - Successfully installed marionette-driver 11:03:27 INFO - Cleaning up... 11:03:27 INFO - Return code: 0 11:03:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:03: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')]} 11:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03: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')]} 11:03:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:27 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 0x10d661c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d665768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2c856df40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3b7d30>, '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 0x10d381cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3a8be8>, '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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:03:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:03:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:03:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jQ5fQRZYTP/Render', 11:03:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:27 INFO - 'HOME': '/Users/cltbld', 11:03:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:27 INFO - 'LOGNAME': 'cltbld', 11:03:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:27 INFO - 'MOZ_NO_REMOTE': '1', 11:03:27 INFO - 'NO_EM_RESTART': '1', 11:03:27 INFO - 'PAGER': '/bin/cat', 11:03:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:27 INFO - 'PWD': '/builds/slave/test', 11:03:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:27 INFO - 'SHELL': '/bin/bash', 11:03:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXHxoqjvFs/Listeners', 11:03:27 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 11:03:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:27 INFO - 'USER': 'cltbld', 11:03:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:27 INFO - 'XPC_FLAGS': '0x0', 11:03:27 INFO - 'XPC_SERVICE_NAME': '0', 11:03:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:03:27 INFO - Cleaning up... 11:03:27 INFO - Return code: 0 11:03:27 INFO - Running post-action listener: _resource_record_post_action 11:03:27 INFO - Running post-action listener: _start_resource_monitoring 11:03:27 INFO - Starting resource monitoring. 11:03:27 INFO - [mozharness: 2017-06-14 18:03:27.723552Z] Finished create-virtualenv step (success) 11:03:27 INFO - [mozharness: 2017-06-14 18:03:27.724512Z] Running install step. 11:03:27 INFO - Running pre-action listener: _resource_record_pre_action 11:03:27 INFO - Running main action method: install 11:03:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:03:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:03:27 INFO - Reading from file tmpfile_stdout 11:03:27 INFO - Detecting whether we're running mozinstall >=1.0... 11:03:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:03:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:03:28 INFO - Reading from file tmpfile_stdout 11:03:28 INFO - Output received: 11:03:28 INFO - Usage: mozinstall [options] installer 11:03:28 INFO - Options: 11:03:28 INFO - -h, --help show this help message and exit 11:03:28 INFO - -d DEST, --destination=DEST 11:03:28 INFO - Directory to install application into. [default: 11:03:28 INFO - "/builds/slave/test"] 11:03:28 INFO - --app=APP Application being installed. [default: firefox] 11:03:28 INFO - mkdir: /builds/slave/test/build/application 11:03:28 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'] 11:03:28 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 11:03:49 INFO - Reading from file tmpfile_stdout 11:03:49 INFO - Output received: 11:03:49 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:03:49 INFO - Running post-action listener: _resource_record_post_action 11:03:49 INFO - [mozharness: 2017-06-14 18:03:49.232918Z] Finished install step (success) 11:03:49 INFO - [mozharness: 2017-06-14 18:03:49.233097Z] Running setup-mitmproxy step. 11:03:49 INFO - Running pre-action listener: _resource_record_pre_action 11:03:49 INFO - Running main action method: setup_mitmproxy 11:03:49 INFO - Skipping: mitmproxy is not required 11:03:49 INFO - Running post-action listener: _resource_record_post_action 11:03:49 INFO - [mozharness: 2017-06-14 18:03:49.233807Z] Finished setup-mitmproxy step (success) 11:03:49 INFO - [mozharness: 2017-06-14 18:03:49.233942Z] Running run-tests step. 11:03:49 INFO - Running pre-action listener: _resource_record_pre_action 11:03:49 INFO - Running main action method: run_tests 11:03:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:03:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:03:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:03:49 INFO - 2017-06-14 11:03:49.248 screenresolution[1624:7411] starting screenresolution argv=screenresolution get 11:03:49 INFO - 2017-06-14 11:03:49.251 screenresolution[1624:7411] Display 0: 1600x1200x32@60 11:03:49 INFO - 2017-06-14 11:03:49.257 screenresolution[1625:7414] starting screenresolution argv=screenresolution list 11:03:49 INFO - Available Modes on Display 0 11:03:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:03:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:03:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:03:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:03:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:03:49 INFO - Intel Iris: 11:03:49 INFO - Chipset Model: Intel Iris 11:03:49 INFO - Type: GPU 11:03:49 INFO - Bus: Built-In 11:03:49 INFO - VRAM (Dynamic, Max): 1536 MB 11:03:49 INFO - Vendor: Intel (0x8086) 11:03:49 INFO - Device ID: 0x0a2e 11:03:49 INFO - Revision ID: 0x0009 11:03:49 INFO - Displays: 11:03:49 INFO - Display: 11:03:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:03:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:03:49 INFO - Display Serial Number: 1600x1200 60 11:03:49 INFO - Main Display: Yes 11:03:49 INFO - Mirror: Off 11:03:49 INFO - Online: Yes 11:03:49 INFO - Rotation: Supported 11:03:49 INFO - Return code: 0 11:03:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:03:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:03:49 INFO - Python 2.7.3 11:03:49 INFO - Return code: 0 11:03:49 INFO - grabbing minidump binary from tooltool 11:03:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:49 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 0x10d3b7d30>, '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 0x10d381cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3a8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:03:49 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:03:49 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:03:49 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:03:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:03:49 INFO - Return code: 0 11:03:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:03:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:03:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:03:49 INFO - ENV: RUST_BACKTRACE is now 1 11:03:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:03:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:03:49 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--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 11:03:49 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0205 --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 11:03:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jQ5fQRZYTP/Render', 11:03:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:49 INFO - 'HOME': '/Users/cltbld', 11:03:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:49 INFO - 'LOGNAME': 'cltbld', 11:03:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:03:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:03:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:49 INFO - 'MOZ_NO_REMOTE': '1', 11:03:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:03:49 INFO - 'NO_EM_RESTART': '1', 11:03:49 INFO - 'PAGER': '/bin/cat', 11:03:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:49 INFO - 'PWD': '/builds/slave/test', 11:03:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:03:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:49 INFO - 'RUST_BACKTRACE': '1', 11:03:49 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:03:49 INFO - 'SHELL': '/bin/bash', 11:03:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KXHxoqjvFs/Listeners', 11:03:49 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 11:03:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:49 INFO - 'USER': 'cltbld', 11:03:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:49 INFO - 'XPC_FLAGS': '0x0', 11:03:49 INFO - 'XPC_SERVICE_NAME': '0', 11:03:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:49 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--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 11:03:49 INFO - mozversion application_buildid: 20170614093034 11:03:49 INFO - mozversion application_changeset: 21965d67c5fe68e82b9c3d2a04ba128baf2bd589 11:03:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:03:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:03:49 INFO - mozversion application_name: Firefox 11:03:49 INFO - mozversion application_remotingname: firefox-dev 11:03:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:03:49 INFO - mozversion application_vendor: Mozilla 11:03:49 INFO - mozversion application_version: 55.0 11:03:49 INFO - mozversion platform_buildid: 20170614093034 11:03:49 INFO - mozversion platform_changeset: 21965d67c5fe68e82b9c3d2a04ba128baf2bd589 11:03:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:03:49 INFO - mozversion platform_version: 55.0 11:03:49 INFO - using testdate: 1497463429 11:03:49 INFO - actual date: 1497463429 11:03:49 INFO - starting webserver on 'localhost:49229' 11:03:49 INFO - SUITE-START | Running 2 tests 11:03:49 INFO - TEST-START | dromaeo_css 11:03:49 INFO - operating with platform_type : mac_ 11:03:49 INFO - Initialising browser for dromaeo_css test... 11:03:49 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpFEu0dD/profile 11:03:49 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 11:04:23 INFO - TEST-INFO | 1635: killed by SIGTERM 11:04:23 INFO - Browser initialized. 11:04:23 INFO - Running cycle 1/1 for dromaeo_css test... 11:04:23 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpFEu0dD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:04:25 INFO - PID 1646 | 2017-06-14 11:04:25.307 plugin-container[1647:7978] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 11:04:25 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:04:25 INFO - PID 1646 | 2017-06-14 11:04:25.308 plugin-container[1647:7978] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 11:04:25 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:04:25 INFO - PID 1646 | 2017-06-14 11:04:25.308 plugin-container[1647:7978] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:04:26 INFO - PID 1646 | 2017-06-14 11:04:26.098 plugin-container[1648:8022] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 11:04:26 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:04:26 INFO - PID 1646 | 2017-06-14 11:04:26.099 plugin-container[1648:8022] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 11:04:26 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:04:26 INFO - PID 1646 | 2017-06-14 11:04:26.099 plugin-container[1648:8022] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:04:35 INFO - PID 1646 | RSS: Main: 213999616 11:04:35 INFO - PID 1646 | 11:07:38 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 11:07:40 INFO - PID 1646 | RSS: Main: 232169472 11:07:40 INFO - PID 1646 | 11:10:41 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 11:10:42 INFO - PID 1646 | RSS: Main: 239177728 11:10:42 INFO - PID 1646 | 11:13:43 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 11:13:44 INFO - PID 1646 | RSS: Main: 243101696 11:13:44 INFO - PID 1646 | 11:16:46 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 11:16:46 INFO - PID 1646 | RSS: Main: 244822016 11:16:46 INFO - PID 1646 | 11:19:49 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 11:19:50 INFO - PID 1646 | RSS: Main: 245264384 11:19:50 INFO - PID 1646 | 11:22:52 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 11:22:52 INFO - PID 1646 | RSS: Main: 243867648 11:22:52 INFO - PID 1646 | 11:22:52 INFO - PID 1646 | __start_tp_report 11:22:52 INFO - PID 1646 | _x_x_mozilla_page_load 11:22:52 INFO - PID 1646 | _x_x_mozilla_page_load_details 11:22:52 INFO - PID 1646 | |i|pagename|runs| 11:22:52 INFO - PID 1646 | |0;dojo.html;2877.726615971482;3606.2246616977345;3652.68952139068;3668.724845636577;3674.7243956703237;5900.439458251464;6313.558050936436;6315.810525684231;6332.271788744296;6335;6381.8085457436355;6388.8083357499345;6390.233172019364;6392.584482008666;6401.839954001152;5602.7758889644365;5628.465295796903;5638.859028524279;5696.231008813798;5762.9135562966585;5611.80358687446;5629.971850140743;5641.717914104278;5691.08942569189;5704.657720536781;4492.281235002384;5471.671699698011;5515.924394743027;5529.419410961871;5625.46558076982;4976.4525902150735;5301.231321458367;5560.277163968699;5589.776408943638;5603.579731520112;5456.590755693299;5557.416471270499;5558.249636299129;5581.49766521015;5589.161625756128;6252.906206406908;6313.494920406357;6319.8736025279695;6322.241331040259;6339.524535659844;6664.233613134455;6732.360425759594;6844.648815253358;6856.211535673362;6882.724691012353;6844.110265665481;6872.896906546406;6945.965270173668;6983.8603227935155;6992.230854605989;6418.422341989243;6426.967865160691;6530.869382612321;6537.967310163419;6557.934420655828;7156.320149585781;7169.749058782918;7172.354488096096;7174.713011479483;7200.531965422231;6597.60414375139;6680.799576012728;6700.631465269359;6714.731410743613;6798.592084474947;6830.077939478107;6937.375636192767;6952.374286314155;6999.1950925644205;7004.894926576106;6804.931950680529;6895.448364130857;6905.827354316182;6912.447004239649;6921.688524016431;6916.930830691729;6935.167779866449;6937.340952609494;6954.721811127498;6955.513114082067;5509.228707980886;5511.862203444865;5522.116461366162;5528.889422211533;5531.834044978657;4128.050548373891;4733.337332773414;4735.289706544046;4764.809407623726;4911.484294149134;5496.862578435491;6324.683765811691;6404.327545607737;6431.389018043278;6452.774152904626;7017.087778588751;7027.683754231072;7037.22590515043;7055.0475685782785;7083;6485.902711459332;6487.091807147003;6495.415412612888;6499.350064993463;6524.902126468107;6100.969495152496;6118.847028824315;6141.447269745744;6145.078238264206;6176.505879529627;6087.391260873877;6254.874122657874;6889;7086.716531338792;7093.503454758324;6036.788712395045;6566.507511936719;6892.896606550806;6900.551464154814;7000.894986575104;7072.115985501812;7156.785296441115;7171.605561693948;7181.712731490682;7223.061002069908;6911.239763625897;6917;6926.307369263033;6934.514583979073;6948.0272761812375;7025.156981162293;7070.787876363717;7080.185778635523;7081.504294699322;7087.14954205478;6576.80269591913;6687.230968438692;6695.397414232742;6794.796156115324;6826.249112597511;6736.93263067363;6740.359665831738;6780.25417204117;6792.218894827158;6844.247132815486;5792.493951572537;6666.100076489805;6679.23188833271;6680.76617318411;6697.899531506934;6796.524243302922;6806.76176333826;6807.795766127024;6820.624865632438;6822.488313376417;6445.065465507381;6508.088867558451;6524.445422139172;6526.902096468461;6531.706073226621 11:22:52 INFO - PID 1646 | |1;ext.html;794.7615715285413;822.6873787960574;834.1116710703101;851.3657325292656;862.2412277196066;37684.99999999996;38426.61573384266;38475.615243847555;38507;38555.61444385556;38250.426243606365;38502;38587.03532411684;38616;38786.612133878654;7453.180150183489;7578.886316705254;7583.393328533704;7590.089189297264;8034.758957231264;8166.428350015502;8189.7133600324005;8270.751877443687;8280.130586288444;8281.420300578962;8158.469699469545;8214.260716535511;8281.668733250694;8283.751487455356;8299.751007469786;8176.3050140738105;8218.835623287561;8227.13615070415;8267.29727973123;8268.214519620626;8098.433109682324;8103.270705636521;8123.95938020312;8149.918500814975;8179.222973817477;36017.63982360169;36155;36193.63806361929;36222.63777362245;36237.45643815345;3189.8675970030768;3581.623929487392;3583.140046388848;3598.1904071583945;3622.492851000862;29481.41037179266;29967.850160749276;29997.950071747604;30051.948181813532;30078.84960575205;3038.589790378293;3126.093432904455;3132.1543183340373;3142.591463109781;3167.904962851118;246379;249166;250063;326278;328157;46831;47398.28902566464;47687.28469072967;47959.04081918412;48008.79978000508;38864.6113538861;49489.50510494921;50033.749831250614;50248;50303.4908952732;15323.31045102973;15412.383504659689;15419.845801542066;15430.5370838875;15434.996725212824;12486.250824950532;12769.936150319189;12775.425105870258;12781.552645657357;12790.296533690736;13272.336383180802;13459.730805383837;13478.123921945042;13515.391807368693;13541.661458463617;5841.094630332305;5873.853153671193;5890.440408161304;5900.852478687982;5915.792947246826;986.8421052631688;990.8166989106894;992.161623428202;1048.6067724603274;1050.0916707048348;23212.651810222862;23407.882960585088;23475.41311467227;23613;24334.148304809245;934.5000424772714;1033.421284080917;1113.5879724501985;1135.3358285402967;1157.7669781682482;10925.617603383844;12027.458764355624;12535;12728.681782955315;12801.975841932623;287139;291786;347763;350244;352732;53756;54175.72912135414;54270.72864635652;54485;54536;1460.2552698124034;1506.540505145919;1509.1925819686635;1512.3572481695455;1523.253605733205;22042.779572204072;22357.552848942603;22904.77095229093;23181.304560863202;23838.284851454486;38592.42111368388;38719;38809.2238155241;38821.41767873427;38905.6383026604;302788;303700;307012;308716.9128308688;309024;29049.854750726958;29508.262293442836;32029.199270018435;34858;34961.65038349584;41265.35265681271;42042.57957420387;43751.78124109359;43754.124917500834;43906.34140487959;27673.72326276792;27731;28808.711912880604;30708.539371909887;30720.692793071783;29354.70645293605;29759.553606695485;29925.700742992292;30481.695183047887;30531.389372211986;33029.67881284818;34633.65366346405;35682.64317356793;36152.819235903655;36309;28751.993680221927;29419.85290073536;29509.704902950696;29655.703442965296;30546.930857419884;20331.08510117078;21347.50567460325;23014.539709206056;23801;24531.63202551999 11:22:52 INFO - PID 1646 | |2;jquery.html;465.6856621780299;499.90251900879343;500.33455504179466;513.8150265904274;531.7181893596395;21166.835824029673;21833.454163645936;21841.344759657197;21855.344339669817;21924.45188870275;24379.634305485433;24567.63148552773;24616;24916.50166996659;24952.376190595245;6332.113504109417;6478.255000674912;6481.708323125447;6487.091807147003;6506.1541999539995;6303.653299068549;6341.239051313826;6343.175387199658;6344;6358.395952384539;5292.36491621006;5619.5785316101255;5919.674417907014;6277.497800175975;6319.715612797435;6228.501719862423;6269.373062693694;6270.749170033216;6272.780452684134;6279.811605651837;5755.683437410982;5757.568182386338;5765.855853603652;5778.508826749731;5780.335261444946;28672.71327286721;28673.85663071692;29872.402551949057;30082.699173008208;30505.69494305073;2261.061659411342;2301.2290882554366;2324.2213858357472;2330.8368414211013;2332.696749422581;18822.964736939462;19373;19411.223551058083;19423.90288048565;19466.610667786706;2188.387251569547;2189.5949249388914;2233.6426171812595;2234.6759719840534;2274.2836006658017;164032;166230.71271124054;173842;174090;202061.98969005208;13283.535076272285;14516.927415362856;15103.924480377527;15382.692346153239;15855.58946490377;20871.165153394;20888.686669699968;20895.47761305949;20928.581428371654;20954.79045209559;8561.614727337284;10418.739531511774;10428.791424171626;10461.320014199053;10467.162626989822;7918.683252669829;9796.216302695768;9808.558614862348;9827.262955278382;9850.605975761033;7520.924790752022;8176.427650064438;9288.34981551285;9349.90650093504;9394.530273486434;2555.1695698897934;2584.2634849067927;2584.987075064613;2587.2626301503983;2676.2372723773633;923.9353245272766;924.8705181274627;933.9213208743912;969.1277849935115;979.8775153105862;19512.804871951383;21396.679049813953;22553.323400298017;22584;22862;951.4766878216954;983.1446641422009;1006.6728313298061;1022.25375475903;1033.844923261517;10877.945610271898;10976.780464390828;11250.043746281493;11496.482658280398;11805.638872225589;580995;594477;610147;611423;621793;160063.79872402488;162054;162094.1895290516;165450;168072.7981800465;1216.1243904388825;1251.7559075980096;1259.5528587351541;1260.1305099481328;1262.1228246368685;19313.4205973821;19765.912874792026;23985;25152;25198.74801251964;63714;73444.8983265262;74857;76094;77536.2246377529;74645.253547466;74809.25190748023;75171.62414187894;77700.61149694442;77984.2201577977;16500.42248521296;16948.491545253662;17026.404075857285;17207.225674844412;17322.82677173212;18439.446816595522;18442.907785460986;18515.907420462812;18517.722234166227;18802.247910083443;14356.712865742835;14708.41166353334;14797.112173269208;14985.2507374633;15218;13117.344132793512;13245.980210265865;13291.66770830737;13858.376373063034;14255.572332830032;13462.932685336511;15535.300911458786;15628.4530041448;15647.669948054543;15661.921690391475;16607.667846643242;16667.083310418042;16700;16807.495775126765;17060.402885898933;14853;14883.958122931292;14907.701845962803;14967.476138335105;15000.024998375071 11:22:52 INFO - PID 1646 | |3;mootools.html;1426.108682073704;1474;1480.68165344451;1506.8191816981957;1525.77113432985;13709.725805483884;14236;14259.50091746789;14268.785968210484;14305.356258968346;11660.41697915103;11950.82073768892;13200.141990770617;13231;14222.288885555707;2814.2120206342206;2859.5273434181413;2863;2863.241241071107;2867.1111955293823;2770.0997175917805;2802.2714094335415;2809.929751756212;2814.226087825854;2818.957715634267;2369.5616310982437;2777.125205560242;2793.385455199853;2821.4357128574266;2826.985865070672;2651.363672718553;2756.8208066475713;2834.9858250708617;2840.3893162969853;2843.260752204421;2796.3708165662765;2821.266470717628;2833.914982550527;2847.886084556615;2852.429514097189;13644.044916855824;13673.589792306248;13696.93151534236;13739.450421983109;13756.17462952216;7354.963225183893;7776.572288524129;7781.805454863617;7781.8832717509285;7783.883241751321;4629.930551041703;4798.112349215371;4798.592119669832;4899.461059283476;4917;1183.2959389163434;1369.2400717601727;1372.876441120294;1376.0849035391452;1397.6855207578315;20843.478913027146;21052.368428947157;21058.157673693033;21076.15695372183;21078;6695.330466953265;6747.831304217336;6755.290694477009;6773.153355830521;6848.623325717033;14536.491222807152;14548;14549;14558.490452834312;14591.124532528082;7443.144038435541;7540.472166948261;7589.468737188344;7590.1650818409935;7597.164311925684;6853.074834897325;6923.5499692519825;6935.271796461399;6971.721131154697;6997.895031574531;4671.065786842645;5081.974590127025;5230.189320655304;5298.258243845865;5335.599830012687;1283.5250957145915;1455.5924341184486;1525.9389624414898;1538.7461068923503;1557.244736302892;805.4522924411457;809.9311558517592;826.2068414322198;829.344817594106;849.222960990699;7224.313690199423;7342.559446433231;7917.0895347034675;8012.439129260896;8208.794780130545;637.3085202555187;670.8490589617395;687.1342108942769;702.4345401951479;702.6943279673359;3791.976166435047;4454.888627784331;4903.656744027955;5176.922346164811;5216.26972223889;17711.291548337922;18170.727439088423;18253.361132360304;18549.072546372627;18749.25002999892;18108.275668973092;18677.346292879683;18686.972216528222;18731.531711707044;18862.528436788914;8391.076981532027;9461.290403219786;9464.574094165779;9567.378120422289;9667.70996870095;7405.666744996596;7455.403567714461;7531.736389226351;7589.203133670885;7913.485623434689;16187.514374568782;16298.103604301878;16415.83584164143;16657.083860387796;16682.916585416995;12642.810357844824;16671.416500422427;17055.232514536627;17469.30122795074;17565;9733.367331123454;9826.4104153751;9878.50607469638;10598.470076496298;11054.944725276322;12836.294003829878;12910.67723306925;12992.610221693365;12997.675058123623;13003.674908127372;9936.701898943043;10033.548490317808;10151.94924025375;10286.125679317342;10591.682249532527;9201.30990175753;10402.063814256753;10452.895471045193;10638.89361106379;10744.086752625803;8981.506017169118;9156.954215228881;9187.173154415867;9198.43626583469;9210.078992100736;6598;6814.182298124058;6862.828429289308;7002.544834585736;7109.502334836719;6755.695993680394;6794.524383293122;7022.754203602849;7098.36114749675;7129.39400150993 11:22:52 INFO - PID 1646 | |4;prototype.html;421.88187307553875;436.26053838743303;445.80607435765427;476.5948943398112;477.6346095237144;6129.7740451909685;6364.490840732742;6510.967445162773;6557.409833115024;6589.242237142718;4587.266037434003;5260.684358938466;6118.510519158468;6277.717502712378;6301.842453938642;4754.762261886909;4764.666473346867;4779.115863565235;4786.593139583139;4805.495422980589;3698.85204591816;4768.97615511922;4770.522947705219;4772.475027746962;4779.139754844126;3966.6231707987695;4734.857954261378;4747.26417405301;4769.211545670373;4782.808687652489;4125.546189919107;4547.113312903985;4746.335513028162;4751.738654374008;4759.690620109682;4158.625723684852;4161.417401563784;4173.853915112956;4185.246655601991;4206.632844325606;6163.784267550659;6187.412195841433;6193.076504179188;6196.535259855484;6224.9688751556405;5666.490015898547;5676.231965149294;5678.659280443187;5686.772529098831;5747.655140691572;5598.664080155163;6581.440577550914;6799.354061364212;6831.897521537181;6836.897446538306;3621.800800955946;4550.362949187116;4617.746023968681;4808.990112076433;4811.831585894477;6000.309964354112;7273.38176255019;7293.927060729431;7295.89056164158;7355.227701091362;4878.9512104878495;4919;4930.137225985438;4932.432770231398;4937.753112344369;4166.708330416903;4931.48219436954;4950.653454258239;4999.975000125049;5004.749762511859;4512.390827238347;4763.976180119077;4923.803047878117;5249.895002099936;5690.3456102548735;6863.862722745517;6915.688794004282;6922.2731613180895;6931.202911665223;6949.270326615632;1696.821833707443;1707.4365459398368;1714.1000974488245;1736;1889.395393474075;1137.7895089408487;1164.6738913104343;1241.5716577780652;1346.0241325039465;1346.9728059270735;6115;6195.287541932736;6325.367463253638;6422.967885160638;6427.58220715652;6275;6966.893768356248;7020.824479388056;7110;7155.463340249398;6244.313125556185;6345.302016778151;6353.014083380804;6456.160699109179;6466.967665161644;3253.8210398428314;3401.6598340165783;3603.072650810629;3675.3568125577917;3743.326201283795;6055.878882422327;6813.420859226922;7307.7807665770115;7319.5242309249725;7395.482316237919;6125.519164738288;7342.486025978237;7400.706685524998;7403.51877127985;7417.666205020787;7273.636318184175;7274.272572742895;7293.124825021031;7300.138464691469;7332.8900066499045;5880.26496687914;6980.301969802979;7108.715651373887;7116.039334689751;7123.430125590044;6442.677866106769;6694.364035416627;6704.463642908653;6967.686454109678;7010.824729381602;6631.403173714196;6651.935690289531;7303.707851685873;7314.4148468123485;7343.8898416522725;4626.953730462652;4670.953290467051;4719.244920812654;4833.564979151894;4913.926291105565;6538.640374779433;7163.498555101093;7198.316159964795;7252.694514987142;7297.671604777692;6038.879222415615;6890.793276201722;6933.221394340198;6958.408535274356;6983.546069505466;5650.406707295675;5961.612495187799;6291.96854015727;6558.934410655832;6566.800121959197;6011.677430965181;6154.815355539341;6730.798076057726;6774.187097548326;6816.250212476524;6326.652034137983;6851.623160726208;6874.621895795779;6877.552959057845;6900.89585666291;5816.982028144973;6628.177251255943;6700.396964273239;6705.765298214539;6758.93241067583 11:22:52 INFO - PID 1646 | |5;yui.html;833.4540875726401;840.8276303357811;841.3185319890885;864.5302984925628;869.4088020146299;14011.36948837301;16682.666346673075;16684.915480493775;16892.493225203234;16931.98408095515;17056;17495.125243737795;18571;18619.34832280867;18648.72026919594;1001.8547310639952;1011.1051719228462;1028.9742756431074;1029.2075102171323;1031.7575369788094;1026.4303311662024;1039.6621098143096;1040.2042437535292;1040.33938449085;1047.3820445936897;950.9381890177151;995.4176806568166;1062.702443315869;1063.702163394247;1071.8820929697765;1045.513836066229;1054.520193312041;1058.8941105889348;1060.4114716332433;1061.6708820265703;626.9623822570614;760.6691089376084;764.5259938837956;771.155584634824;771.5332224004509;17036.2333694984;17129.571760705956;17138.657226855517;17340.653186936317;17381.391651292142;17404;17597.824021759876;17739.556511087325;17802.55493612657;17830.643387132186;14950;15017.098974061482;15104.018238814442;15122.924385378114;15141.470048548248;3520.3487354839435;3537.982310088433;3568.8750893718857;3572.964270357289;3826.9808650956847;112701.43649281701;133235;136473;154044.2297788515;156281.65577516233;13666.52167174144;13748.65628359299;14008.789868151986;14016.439342426393;14047;25957.961681532906;27047.86476067607;27273.86363068172;27307.726922730915;27382.315442113708;8007.919920800718;8101.311388531923;8192.959035204905;8267.338612910953;8274.420790544724;3916.1580260244186;3956.951407876915;3969.226000929791;3988.8205030773684;3993.4409182714444;4549.454065512094;4668.95331046692;4719.126961512131;4783.186858234039;5059.645824792303;857.8798968144465;894.7226359828474;899.1727610598265;1067.51961617271;1075.5052675769077;584.3951510186936;637.3626373626373;640.6828619833212;682.4642655515397;697.9720811167497;11422.31466112036;13134.474621015052;13939.721205575832;13949.16305021702;14026.368813403422;633.2369494758805;636.9808905732835;650.3821369698805;657.3131078023476;672.5695554844904;5391.595630327663;5657.915131273035;5672.347680016768;5775.971120144372;5981.6710080945095;129850;139836;140599;141440;142988;113400;115216;116680;120369.7963020376;123766;98444;102284.48857755664;104140;104548;105538.4169237462;12030.458629361525;12419.379031048593;12439.06706996997;12545;12701.618951431472;45566.493842838965;45752.771236143606;45900.31149532693;46621;47417.52582474131;85780;86315;88151.11848881685;88495;89192;14533.563993080224;17015;17848.28606855763;18034.2786288547;18069.548261293046;18591.814081859007;19183.52041198981;19301.710474342613;19373.128209230337;19487.12307946174;17144.82855171482;17802;17888.821111788715;17950.730739039187;18190.272389104815;16973.745393818856;17517.299308027537;17743.201555929754;18098.185581648595;18136.818631813512;16918.984860908386;17498.300067997137;17567.12164391801;17827.554311141805;17971.640567188846;11184.440777961232;11611.88388116108;11849.289042657469;11965.521379144737;12542.93728531388;11219.831702524305;11906.047516198856;12864.326587233074;13452.93273533626;13738.969577281545 11:22:52 INFO - PID 1646 | __end_tp_report 11:22:52 INFO - PID 1646 | __start_cc_report 11:22:52 INFO - PID 1646 | _x_x_mozilla_cycle_collect,405 11:22:52 INFO - PID 1646 | __end_cc_report 11:22:52 INFO - PID 1646 | __startTimestamp1497464572343__endTimestamp 11:22:52 INFO - PID 1646 | 11:22:52 INFO - PID 1646 | ------- Summary: start ------- 11:22:52 INFO - PID 1646 | Number of tests: 6 11:22:52 INFO - PID 1646 | 11:22:52 INFO - PID 1646 | [#0] dojo.html Cycles:165 Average:6327.75 Median:6537.97 stddev:809.65 (12.4%) stddev-sans-first:765.56 11:22:52 INFO - PID 1646 | Values: 2877.7 3606.2 3652.7 3668.7 3674.7 5900.4 6313.6 6315.8 6332.3 6335.0 6381.8 6388.8 6390.2 6392.6 6401.8 5602.8 5628.5 5638.9 5696.2 5762.9 5611.8 5630.0 5641.7 5691.1 5704.7 4492.3 5471.7 5515.9 5529.4 5625.5 4976.5 5301.2 5560.3 5589.8 5603.6 5456.6 5557.4 5558.2 5581.5 5589.2 6252.9 6313.5 6319.9 6322.2 6339.5 6664.2 6732.4 6844.6 6856.2 6882.7 6844.1 6872.9 6946.0 6983.9 6992.2 6418.4 6427.0 6530.9 6538.0 6557.9 7156.3 7169.7 7172.4 7174.7 7200.5 6597.6 6680.8 6700.6 6714.7 6798.6 6830.1 6937.4 6952.4 6999.2 7004.9 6804.9 6895.4 6905.8 6912.4 6921.7 6916.9 6935.2 6937.3 6954.7 6955.5 5509.2 5511.9 5522.1 5528.9 5531.8 4128.1 4733.3 4735.3 4764.8 4911.5 5496.9 6324.7 6404.3 6431.4 6452.8 7017.1 7027.7 7037.2 7055.0 7083.0 6485.9 6487.1 6495.4 6499.4 6524.9 6101.0 6118.8 6141.4 6145.1 6176.5 6087.4 6254.9 6889.0 7086.7 7093.5 6036.8 6566.5 6892.9 6900.6 7000.9 7072.1 7156.8 7171.6 7181.7 7223.1 6911.2 6917.0 6926.3 6934.5 6948.0 7025.2 7070.8 7080.2 7081.5 7087.1 6576.8 6687.2 6695.4 6794.8 6826.2 6736.9 6740.4 6780.3 6792.2 6844.2 5792.5 6666.1 6679.2 6680.8 6697.9 6796.5 6806.8 6807.8 6820.6 6822.5 6445.1 6508.1 6524.4 6526.9 6531.7 11:22:52 INFO - PID 1646 | 11:22:52 INFO - PID 1646 | [#1] ext.html Cycles:180 Average:45075.90 Median:34909.83 stddev:80378.74 (230.2%) stddev-sans-first:80535.46 11:22:52 INFO - PID 1646 | Values: 794.8 822.7 834.1 851.4 862.2 37685.0 38426.6 38475.6 38507.0 38555.6 38250.4 38502.0 38587.0 38616.0 38786.6 7453.2 7578.9 7583.4 7590.1 8034.8 8166.4 8189.7 8270.8 8280.1 8281.4 8158.5 8214.3 8281.7 8283.8 8299.8 8176.3 8218.8 8227.1 8267.3 8268.2 8098.4 8103.3 8124.0 8149.9 8179.2 36017.6 36155.0 36193.6 36222.6 36237.5 3189.9 3581.6 3583.1 3598.2 3622.5 29481.4 29967.9 29998.0 30051.9 30078.8 3038.6 3126.1 3132.2 3142.6 3167.9 246379.0 249166.0 250063.0 326278.0 328157.0 46831.0 47398.3 47687.3 47959.0 48008.8 38864.6 49489.5 50033.7 50248.0 50303.5 15323.3 15412.4 15419.8 15430.5 15435.0 12486.3 12769.9 12775.4 12781.6 12790.3 13272.3 13459.7 13478.1 13515.4 13541.7 5841.1 5873.9 5890.4 5900.9 5915.8 986.8 990.8 992.2 1048.6 1050.1 23212.7 23407.9 23475.4 23613.0 24334.1 934.5 1033.4 1113.6 1135.3 1157.8 10925.6 12027.5 12535.0 12728.7 12802.0 287139.0 291786.0 347763.0 350244.0 352732.0 53756.0 54175.7 54270.7 54485.0 54536.0 1460.3 1506.5 1509.2 1512.4 1523.3 22042.8 22357.6 22904.8 23181.3 23838.3 38592.4 38719.0 38809.2 38821.4 38905.6 302788.0 303700.0 307012.0 308716.9 309024.0 29049.9 29508.3 32029.2 34858.0 34961.7 41265.4 42042.6 43751.8 43754.1 43906.3 27673.7 27731.0 28808.7 30708.5 30720.7 29354.7 29759.6 29925.7 30481.7 30531.4 33029.7 34633.7 35682.6 36152.8 36309.0 28752.0 29419.9 29509.7 29655.7 30546.9 20331.1 21347.5 23014.5 23801.0 24531.6 11:22:52 INFO - PID 1646 | 11:22:52 INFO - PID 1646 | [#2] jquery.html Cycles:180 Average:40552.70 Median:22568.66 stddev:103161.40 (457.1%) stddev-sans-first:103406.89 11:22:52 INFO - PID 1646 | Values: 465.7 499.9 500.3 513.8 531.7 21166.8 21833.5 21841.3 21855.3 21924.5 24379.6 24567.6 24616.0 24916.5 24952.4 6332.1 6478.3 6481.7 6487.1 6506.2 6303.7 6341.2 6343.2 6344.0 6358.4 5292.4 5619.6 5919.7 6277.5 6319.7 6228.5 6269.4 6270.7 6272.8 6279.8 5755.7 5757.6 5765.9 5778.5 5780.3 28672.7 28673.9 29872.4 30082.7 30505.7 2261.1 2301.2 2324.2 2330.8 2332.7 18823.0 19373.0 19411.2 19423.9 19466.6 2188.4 2189.6 2233.6 2234.7 2274.3 164032.0 166230.7 173842.0 174090.0 202062.0 13283.5 14516.9 15103.9 15382.7 15855.6 20871.2 20888.7 20895.5 20928.6 20954.8 8561.6 10418.7 10428.8 10461.3 10467.2 7918.7 9796.2 9808.6 9827.3 9850.6 7520.9 8176.4 9288.3 9349.9 9394.5 2555.2 2584.3 2585.0 2587.3 2676.2 923.9 924.9 933.9 969.1 979.9 19512.8 21396.7 22553.3 22584.0 22862.0 951.5 983.1 1006.7 1022.3 1033.8 10877.9 10976.8 11250.0 11496.5 11805.6 580995.0 594477.0 610147.0 611423.0 621793.0 160063.8 162054.0 162094.2 165450.0 168072.8 1216.1 1251.8 1259.6 1260.1 1262.1 19313.4 19765.9 23985.0 25152.0 25198.7 63714.0 73444.9 74857.0 76094.0 77536.2 74645.3 74809.3 75171.6 77700.6 77984.2 16500.4 16948.5 17026.4 17207.2 17322.8 18439.4 18442.9 18515.9 18517.7 18802.2 14356.7 14708.4 14797.1 14985.3 15218.0 13117.3 13246.0 13291.7 13858.4 14255.6 13462.9 15535.3 15628.5 15647.7 15661.9 16607.7 16667.1 16700.0 16807.5 17060.4 14853.0 14884.0 14907.7 14967.5 15000.0 11:22:52 INFO - PID 1646 | 11:22:52 INFO - PID 1646 | [#3] mootools.html Cycles:180 Average:8349.04 Median:2834.45 stddev:5577.86 (196.8%) stddev-sans-first:5569.25 11:22:52 INFO - PID 1646 | Values: 1426.1 1474.0 1480.7 1506.8 1525.8 13709.7 14236.0 14259.5 14268.8 14305.4 11660.4 11950.8 13200.1 13231.0 14222.3 2814.2 2859.5 2863.0 2863.2 2867.1 2770.1 2802.3 2809.9 2814.2 2819.0 2369.6 2777.1 2793.4 2821.4 2827.0 2651.4 2756.8 2835.0 2840.4 2843.3 2796.4 2821.3 2833.9 2847.9 2852.4 13644.0 13673.6 13696.9 13739.5 13756.2 7355.0 7776.6 7781.8 7781.9 7783.9 4629.9 4798.1 4798.6 4899.5 4917.0 1183.3 1369.2 1372.9 1376.1 1397.7 20843.5 21052.4 21058.2 21076.2 21078.0 6695.3 6747.8 6755.3 6773.2 6848.6 14536.5 14548.0 14549.0 14558.5 14591.1 7443.1 7540.5 7589.5 7590.2 7597.2 6853.1 6923.5 6935.3 6971.7 6997.9 4671.1 5082.0 5230.2 5298.3 5335.6 1283.5 1455.6 1525.9 1538.7 1557.2 805.5 809.9 826.2 829.3 849.2 7224.3 7342.6 7917.1 8012.4 8208.8 637.3 670.8 687.1 702.4 702.7 3792.0 4454.9 4903.7 5176.9 5216.3 17711.3 18170.7 18253.4 18549.1 18749.3 18108.3 18677.3 18687.0 18731.5 18862.5 8391.1 9461.3 9464.6 9567.4 9667.7 7405.7 7455.4 7531.7 7589.2 7913.5 16187.5 16298.1 16415.8 16657.1 16682.9 12642.8 16671.4 17055.2 17469.3 17565.0 9733.4 9826.4 9878.5 10598.5 11054.9 12836.3 12910.7 12992.6 12997.7 13003.7 9936.7 10033.5 10151.9 10286.1 10591.7 9201.3 10402.1 10452.9 10638.9 10744.1 8981.5 9157.0 9187.2 9198.4 9210.1 6598.0 6814.2 6862.8 7002.5 7109.5 6755.7 6794.5 7022.8 7098.4 7129.4 11:22:52 INFO - PID 1646 | 11:22:52 INFO - PID 1646 | [#4] prototype.html Cycles:180 Average:5546.53 Median:6159.30 stddev:1710.69 (27.8%) stddev-sans-first:1671.68 11:22:52 INFO - PID 1646 | Values: 421.9 436.3 445.8 476.6 477.6 6129.8 6364.5 6511.0 6557.4 6589.2 4587.3 5260.7 6118.5 6277.7 6301.8 4754.8 4764.7 4779.1 4786.6 4805.5 3698.9 4769.0 4770.5 4772.5 4779.1 3966.6 4734.9 4747.3 4769.2 4782.8 4125.5 4547.1 4746.3 4751.7 4759.7 4158.6 4161.4 4173.9 4185.2 4206.6 6163.8 6187.4 6193.1 6196.5 6225.0 5666.5 5676.2 5678.7 5686.8 5747.7 5598.7 6581.4 6799.4 6831.9 6836.9 3621.8 4550.4 4617.7 4809.0 4811.8 6000.3 7273.4 7293.9 7295.9 7355.2 4879.0 4919.0 4930.1 4932.4 4937.8 4166.7 4931.5 4950.7 5000.0 5004.7 4512.4 4764.0 4923.8 5249.9 5690.3 6863.9 6915.7 6922.3 6931.2 6949.3 1696.8 1707.4 1714.1 1736.0 1889.4 1137.8 1164.7 1241.6 1346.0 1347.0 6115.0 6195.3 6325.4 6423.0 6427.6 6275.0 6966.9 7020.8 7110.0 7155.5 6244.3 6345.3 6353.0 6456.2 6467.0 3253.8 3401.7 3603.1 3675.4 3743.3 6055.9 6813.4 7307.8 7319.5 7395.5 6125.5 7342.5 7400.7 7403.5 7417.7 7273.6 7274.3 7293.1 7300.1 7332.9 5880.3 6980.3 7108.7 7116.0 7123.4 6442.7 6694.4 6704.5 6967.7 7010.8 6631.4 6651.9 7303.7 7314.4 7343.9 4627.0 4671.0 4719.2 4833.6 4913.9 6538.6 7163.5 7198.3 7252.7 7297.7 6038.9 6890.8 6933.2 6958.4 6983.5 5650.4 5961.6 6292.0 6558.9 6566.8 6011.7 6154.8 6730.8 6774.2 6816.3 6326.7 6851.6 6874.6 6877.6 6900.9 5817.0 6628.2 6700.4 6705.8 6758.9 11:22:52 INFO - PID 1646 | 11:22:52 INFO - PID 1646 | [#5] yui.html Cycles:180 Average:25862.98 Median:17668.69 stddev:38755.77 (219.3%) stddev-sans-first:38818.93 11:22:52 INFO - PID 1646 | Values: 833.5 840.8 841.3 864.5 869.4 14011.4 16682.7 16684.9 16892.5 16932.0 17056.0 17495.1 18571.0 18619.3 18648.7 1001.9 1011.1 1029.0 1029.2 1031.8 1026.4 1039.7 1040.2 1040.3 1047.4 950.9 995.4 1062.7 1063.7 1071.9 1045.5 1054.5 1058.9 1060.4 1061.7 627.0 760.7 764.5 771.2 771.5 17036.2 17129.6 17138.7 17340.7 17381.4 17404.0 17597.8 17739.6 17802.6 17830.6 14950.0 15017.1 15104.0 15122.9 15141.5 3520.3 3538.0 3568.9 3573.0 3827.0 112701.4 133235.0 136473.0 154044.2 156281.7 13666.5 13748.7 14008.8 14016.4 14047.0 25958.0 27047.9 27273.9 27307.7 27382.3 8007.9 8101.3 8193.0 8267.3 8274.4 3916.2 3957.0 3969.2 3988.8 3993.4 4549.5 4669.0 4719.1 4783.2 5059.6 857.9 894.7 899.2 1067.5 1075.5 584.4 637.4 640.7 682.5 698.0 11422.3 13134.5 13939.7 13949.2 14026.4 633.2 637.0 650.4 657.3 672.6 5391.6 5657.9 5672.3 5776.0 5981.7 129850.0 139836.0 140599.0 141440.0 142988.0 113400.0 115216.0 116680.0 120369.8 123766.0 98444.0 102284.5 104140.0 104548.0 105538.4 12030.5 12419.4 12439.1 12545.0 12701.6 45566.5 45752.8 45900.3 46621.0 47417.5 85780.0 86315.0 88151.1 88495.0 89192.0 14533.6 17015.0 17848.3 18034.3 18069.5 18591.8 19183.5 19301.7 19373.1 19487.1 17144.8 17802.0 17888.8 17950.7 18190.3 16973.7 17517.3 17743.2 18098.2 18136.8 16919.0 17498.3 17567.1 17827.6 17971.6 11184.4 11611.9 11849.3 11965.5 12542.9 11219.8 11906.0 12864.3 13452.9 13739.0 11:22:52 INFO - PID 1646 | -------- Summary: end -------- 11:22:52 INFO - PID 1646 | 11:22:52 INFO - TEST-INFO | 1646: exit 0 11:22:52 INFO - TEST-OK | dromaeo_css | took 1143005ms 11:22:52 INFO - TEST-START | kraken 11:22:52 INFO - operating with platform_type : mac_ 11:22:52 INFO - Initialising browser for kraken test... 11:22:52 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpedfXGb/profile 11:22:52 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 11:23:26 INFO - TEST-INFO | 1656: killed by SIGTERM 11:23:26 INFO - Browser initialized. 11:23:26 INFO - Running cycle 1/1 for kraken test... 11:23:26 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpedfXGb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:23:27 INFO - PID 1660 | 2017-06-14 11:23:27.838 plugin-container[1661:10530] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9543, name = 'com.apple.tsm.portname' 11:23:27 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:23:27 INFO - PID 1660 | 2017-06-14 11:23:27.839 plugin-container[1661:10530] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450f, name = 'com.apple.CFPasteboardClient' 11:23:27 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:23:27 INFO - PID 1660 | 2017-06-14 11:23:27.839 plugin-container[1661:10530] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:23:28 INFO - PID 1660 | 2017-06-14 11:23:28.631 plugin-container[1662:10576] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 11:23:28 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:23:28 INFO - PID 1660 | 2017-06-14 11:23:28.632 plugin-container[1662:10576] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480f, name = 'com.apple.CFPasteboardClient' 11:23:28 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:23:28 INFO - PID 1660 | 2017-06-14 11:23:28.632 plugin-container[1662:10576] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:23:37 INFO - PID 1660 | RSS: Main: 215093248 11:23:37 INFO - PID 1660 | 11:23:40 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 11:23:41 INFO - PID 1660 | RSS: Main: 228360192 11:23:41 INFO - PID 1660 | 11:23:43 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 11:23:44 INFO - PID 1660 | RSS: Main: 238354432 11:23:44 INFO - PID 1660 | 11:23:47 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 11:23:48 INFO - PID 1660 | RSS: Main: 244359168 11:23:48 INFO - PID 1660 | 11:23:50 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 11:23:51 INFO - PID 1660 | RSS: Main: 245841920 11:23:51 INFO - PID 1660 | 11:23:53 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:23:53 INFO - PID 1660 | RSS: Main: 246325248 11:23:53 INFO - PID 1660 | 11:23:56 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 11:23:57 INFO - PID 1660 | RSS: Main: 250716160 11:23:57 INFO - PID 1660 | 11:24:00 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 11:24:00 INFO - PID 1660 | RSS: Main: 250933248 11:24:00 INFO - PID 1660 | 11:24:03 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 11:24:04 INFO - PID 1660 | RSS: Main: 250843136 11:24:04 INFO - PID 1660 | 11:24:05 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:24:06 INFO - PID 1660 | RSS: Main: 250519552 11:24:06 INFO - PID 1660 | 11:24:08 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:24:08 INFO - PID 1660 | RSS: Main: 250486784 11:24:08 INFO - PID 1660 | 11:24:10 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:24:11 INFO - PID 1660 | RSS: Main: 250429440 11:24:11 INFO - PID 1660 | 11:24:14 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:24:14 INFO - PID 1660 | RSS: Main: 250486784 11:24:14 INFO - PID 1660 | 11:24:17 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:24:18 INFO - PID 1660 | RSS: Main: 250667008 11:24:18 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 11:24:20 INFO - PID 1660 | RSS: Main: 252276736 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | __start_tp_report 11:24:20 INFO - PID 1660 | _x_x_mozilla_page_load 11:24:20 INFO - PID 1660 | _x_x_mozilla_page_load_details 11:24:20 INFO - PID 1660 | |i|pagename|runs| 11:24:20 INFO - PID 1660 | |0;ai-astar;102;116;105;117;117;115;115;103;114;117 11:24:20 INFO - PID 1660 | |1;audio-beat-detection;140;111;161;148;112;125;113;148;139;107 11:24:20 INFO - PID 1660 | |2;audio-dft;228;280;200;197;194;196;196;198;194;196 11:24:20 INFO - PID 1660 | |3;audio-fft;64;107;117;142;86;90;84;73;139;73 11:24:20 INFO - PID 1660 | |4;audio-oscillator;70;71;115;69;69;75;109;68;70;116 11:24:20 INFO - PID 1660 | |5;imaging-gaussian-blur;92;89;89;89;89;89;88;89;88;92 11:24:20 INFO - PID 1660 | |6;imaging-darkroom;96;96;97;96;96;106;149;95;125;97 11:24:20 INFO - PID 1660 | |7;imaging-desaturate;92;94;90;91;93;90;110;89;90;86 11:24:20 INFO - PID 1660 | |8;json-parse-financial;50;49;51;54;52;53;53;53;54;53 11:24:20 INFO - PID 1660 | |9;json-stringify-tinderbox;52;54;56;55;63;62;88;49;50;58 11:24:20 INFO - PID 1660 | |10;stanford-crypto-aes;82;79;80;81;79;79;80;80;79;81 11:24:20 INFO - PID 1660 | |11;stanford-crypto-ccm;133;135;133;134;133;138;135;137;142;160 11:24:20 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;176;179;175;178;201;218;201;176;202;232 11:24:20 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;68;62;65;67;65;68;66;78;77;85 11:24:20 INFO - PID 1660 | __end_tp_report 11:24:20 INFO - PID 1660 | __start_cc_report 11:24:20 INFO - PID 1660 | _x_x_mozilla_cycle_collect,1021 11:24:20 INFO - PID 1660 | __end_cc_report 11:24:20 INFO - PID 1660 | __startTimestamp1497464660530__endTimestamp 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | ------- Summary: start ------- 11:24:20 INFO - PID 1660 | Number of tests: 14 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:112.10 Median:115.50 stddev:6.17 (5.3%) stddev-sans-first:5.36 11:24:20 INFO - PID 1660 | Values: 102.0 116.0 105.0 117.0 117.0 115.0 115.0 103.0 114.0 117.0 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:139.50 stddev:19.20 (13.8%) stddev-sans-first:20.04 11:24:20 INFO - PID 1660 | Values: 140.0 111.0 161.0 148.0 112.0 125.0 113.0 148.0 139.0 107.0 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:207.90 Median:197.50 stddev:27.27 (13.8%) stddev-sans-first:27.94 11:24:20 INFO - PID 1660 | Values: 228.0 280.0 200.0 197.0 194.0 196.0 196.0 198.0 194.0 196.0 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:97.50 Median:73.00 stddev:27.62 (37.8%) stddev-sans-first:26.50 11:24:20 INFO - PID 1660 | Values: 64.0 107.0 117.0 142.0 86.0 90.0 84.0 73.0 139.0 73.0 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:83.20 Median:69.50 stddev:20.95 (30.1%) stddev-sans-first:21.67 11:24:20 INFO - PID 1660 | Values: 70.0 71.0 115.0 69.0 69.0 75.0 109.0 68.0 70.0 116.0 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:89.40 Median:89.00 stddev:1.43 (1.6%) stddev-sans-first:1.17 11:24:20 INFO - PID 1660 | Values: 92.0 89.0 89.0 89.0 89.0 89.0 88.0 89.0 88.0 92.0 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:105.30 Median:96.00 stddev:17.91 (18.7%) stddev-sans-first:18.68 11:24:20 INFO - PID 1660 | Values: 96.0 96.0 97.0 96.0 96.0 106.0 149.0 95.0 125.0 97.0 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:92.50 Median:90.50 stddev:6.54 (7.2%) stddev-sans-first:6.93 11:24:20 INFO - PID 1660 | Values: 92.0 94.0 90.0 91.0 93.0 90.0 110.0 89.0 90.0 86.0 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:53.00 stddev:1.69 (3.2%) stddev-sans-first:1.59 11:24:20 INFO - PID 1660 | Values: 50.0 49.0 51.0 54.0 52.0 53.0 53.0 53.0 54.0 53.0 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:57.00 stddev:11.28 (19.8%) stddev-sans-first:11.71 11:24:20 INFO - PID 1660 | Values: 52.0 54.0 56.0 55.0 63.0 62.0 88.0 49.0 50.0 58.0 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:80.00 stddev:1.05 (1.3%) stddev-sans-first:0.83 11:24:20 INFO - PID 1660 | Values: 82.0 79.0 80.0 81.0 79.0 79.0 80.0 80.0 79.0 81.0 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:138.00 Median:136.00 stddev:8.23 (6.1%) stddev-sans-first:8.53 11:24:20 INFO - PID 1660 | Values: 133.0 135.0 133.0 134.0 133.0 138.0 135.0 137.0 142.0 160.0 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.80 Median:201.00 stddev:20.20 (10.0%) stddev-sans-first:20.37 11:24:20 INFO - PID 1660 | Values: 176.0 179.0 175.0 178.0 201.0 218.0 201.0 176.0 202.0 232.0 11:24:20 INFO - PID 1660 | 11:24:20 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:68.00 stddev:7.34 (10.8%) stddev-sans-first:7.75 11:24:20 INFO - PID 1660 | Values: 68.0 62.0 65.0 67.0 65.0 68.0 66.0 78.0 77.0 85.0 11:24:20 INFO - PID 1660 | -------- Summary: end -------- 11:24:20 INFO - PID 1660 | 11:24:21 INFO - TEST-INFO | 1660: exit 0 11:24:21 INFO - TEST-OK | kraken | took 88456ms 11:24:21 INFO - SUITE-END | took 1231s 11:24:21 INFO - Completed test suite (00:20:31) 11:24:21 INFO - javascript benchmark 11:24:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8428.790985616697, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2877.726615971482, 3606.2246616977345, 3652.68952139068, 3668.724845636577, 3674.7243956703237, 5900.439458251464, 6313.558050936436, 6315.810525684231, 6332.271788744296, 6335.0, 6381.8085457436355, 6388.8083357499345, 6390.233172019364, 6392.584482008666, 6401.839954001152, 5602.7758889644365, 5628.465295796903, 5638.859028524279, 5696.231008813798, 5762.9135562966585, 5611.80358687446, 5629.971850140743, 5641.717914104278, 5691.08942569189, 5704.657720536781, 4492.281235002384, 5471.671699698011, 5515.924394743027, 5529.419410961871, 5625.46558076982, 4976.4525902150735, 5301.231321458367, 5560.277163968699, 5589.776408943638, 5603.579731520112, 5456.590755693299, 5557.416471270499, 5558.249636299129, 5581.49766521015, 5589.161625756128, 6252.906206406908, 6313.494920406357, 6319.8736025279695, 6322.241331040259, 6339.524535659844, 6664.233613134455, 6732.360425759594, 6844.648815253358, 6856.211535673362, 6882.724691012353, 6844.110265665481, 6872.896906546406, 6945.965270173668, 6983.8603227935155, 6992.230854605989, 6418.422341989243, 6426.967865160691, 6530.869382612321, 6537.967310163419, 6557.934420655828, 7156.320149585781, 7169.749058782918, 7172.354488096096, 7174.713011479483, 7200.531965422231, 6597.60414375139, 6680.799576012728, 6700.631465269359, 6714.731410743613, 6798.592084474947, 6830.077939478107, 6937.375636192767, 6952.374286314155, 6999.1950925644205, 7004.894926576106, 6804.931950680529, 6895.448364130857, 6905.827354316182, 6912.447004239649, 6921.688524016431, 6916.930830691729, 6935.167779866449, 6937.340952609494, 6954.721811127498, 6955.513114082067, 5509.228707980886, 5511.862203444865, 5522.116461366162, 5528.889422211533, 5531.834044978657, 4128.050548373891, 4733.337332773414, 4735.289706544046, 4764.809407623726, 4911.484294149134, 5496.862578435491, 6324.683765811691, 6404.327545607737, 6431.389018043278, 6452.774152904626, 7017.087778588751, 7027.683754231072, 7037.22590515043, 7055.0475685782785, 7083.0, 6485.902711459332, 6487.091807147003, 6495.415412612888, 6499.350064993463, 6524.902126468107, 6100.969495152496, 6118.847028824315, 6141.447269745744, 6145.078238264206, 6176.505879529627, 6087.391260873877, 6254.874122657874, 6889.0, 7086.716531338792, 7093.503454758324, 6036.788712395045, 6566.507511936719, 6892.896606550806, 6900.551464154814, 7000.894986575104, 7072.115985501812, 7156.785296441115, 7171.605561693948, 7181.712731490682, 7223.061002069908, 6911.239763625897, 6917.0, 6926.307369263033, 6934.514583979073, 6948.0272761812375, 7025.156981162293, 7070.787876363717, 7080.185778635523, 7081.504294699322, 7087.14954205478, 6576.80269591913, 6687.230968438692, 6695.397414232742, 6794.796156115324, 6826.249112597511, 6736.93263067363, 6740.359665831738, 6780.25417204117, 6792.218894827158, 6844.247132815486, 5792.493951572537, 6666.100076489805, 6679.23188833271, 6680.76617318411, 6697.899531506934, 6796.524243302922, 6806.76176333826, 6807.795766127024, 6820.624865632438, 6822.488313376417, 6445.065465507381, 6508.088867558451, 6524.445422139172, 6526.902096468461, 6531.706073226621], "value": 6268.708624909271, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.7615715285413, 822.6873787960574, 834.1116710703101, 851.3657325292656, 862.2412277196066, 37684.99999999996, 38426.61573384266, 38475.615243847555, 38507.0, 38555.61444385556, 38250.426243606365, 38502.0, 38587.03532411684, 38616.0, 38786.612133878654, 7453.180150183489, 7578.886316705254, 7583.393328533704, 7590.089189297264, 8034.758957231264, 8166.428350015502, 8189.7133600324005, 8270.751877443687, 8280.130586288444, 8281.420300578962, 8158.469699469545, 8214.260716535511, 8281.668733250694, 8283.751487455356, 8299.751007469786, 8176.3050140738105, 8218.835623287561, 8227.13615070415, 8267.29727973123, 8268.214519620626, 8098.433109682324, 8103.270705636521, 8123.95938020312, 8149.918500814975, 8179.222973817477, 36017.63982360169, 36155.0, 36193.63806361929, 36222.63777362245, 36237.45643815345, 3189.8675970030768, 3581.623929487392, 3583.140046388848, 3598.1904071583945, 3622.492851000862, 29481.41037179266, 29967.850160749276, 29997.950071747604, 30051.948181813532, 30078.84960575205, 3038.589790378293, 3126.093432904455, 3132.1543183340373, 3142.591463109781, 3167.904962851118, 246379.0, 249166.0, 250063.0, 326278.0, 328157.0, 46831.0, 47398.28902566464, 47687.28469072967, 47959.04081918412, 48008.79978000508, 38864.6113538861, 49489.50510494921, 50033.749831250614, 50248.0, 50303.4908952732, 15323.31045102973, 15412.383504659689, 15419.845801542066, 15430.5370838875, 15434.996725212824, 12486.250824950532, 12769.936150319189, 12775.425105870258, 12781.552645657357, 12790.296533690736, 13272.336383180802, 13459.730805383837, 13478.123921945042, 13515.391807368693, 13541.661458463617, 5841.094630332305, 5873.853153671193, 5890.440408161304, 5900.852478687982, 5915.792947246826, 986.8421052631688, 990.8166989106894, 992.161623428202, 1048.6067724603274, 1050.0916707048348, 23212.651810222862, 23407.882960585088, 23475.41311467227, 23613.0, 24334.148304809245, 934.5000424772714, 1033.421284080917, 1113.5879724501985, 1135.3358285402967, 1157.7669781682482, 10925.617603383844, 12027.458764355624, 12535.0, 12728.681782955315, 12801.975841932623, 287139.0, 291786.0, 347763.0, 350244.0, 352732.0, 53756.0, 54175.72912135414, 54270.72864635652, 54485.0, 54536.0, 1460.2552698124034, 1506.540505145919, 1509.1925819686635, 1512.3572481695455, 1523.253605733205, 22042.779572204072, 22357.552848942603, 22904.77095229093, 23181.304560863202, 23838.284851454486, 38592.42111368388, 38719.0, 38809.2238155241, 38821.41767873427, 38905.6383026604, 302788.0, 303700.0, 307012.0, 308716.9128308688, 309024.0, 29049.854750726958, 29508.262293442836, 32029.199270018435, 34858.0, 34961.65038349584, 41265.35265681271, 42042.57957420387, 43751.78124109359, 43754.124917500834, 43906.34140487959, 27673.72326276792, 27731.0, 28808.711912880604, 30708.539371909887, 30720.692793071783, 29354.70645293605, 29759.553606695485, 29925.700742992292, 30481.695183047887, 30531.389372211986, 33029.67881284818, 34633.65366346405, 35682.64317356793, 36152.819235903655, 36309.0, 28751.993680221927, 29419.85290073536, 29509.704902950696, 29655.703442965296, 30546.930857419884, 20331.08510117078, 21347.50567460325, 23014.539709206056, 23801.0, 24531.63202551999], "value": 17233.092284643044, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.6856621780299, 499.90251900879343, 500.33455504179466, 513.8150265904274, 531.7181893596395, 21166.835824029673, 21833.454163645936, 21841.344759657197, 21855.344339669817, 21924.45188870275, 24379.634305485433, 24567.63148552773, 24616.0, 24916.50166996659, 24952.376190595245, 6332.113504109417, 6478.255000674912, 6481.708323125447, 6487.091807147003, 6506.1541999539995, 6303.653299068549, 6341.239051313826, 6343.175387199658, 6344.0, 6358.395952384539, 5292.36491621006, 5619.5785316101255, 5919.674417907014, 6277.497800175975, 6319.715612797435, 6228.501719862423, 6269.373062693694, 6270.749170033216, 6272.780452684134, 6279.811605651837, 5755.683437410982, 5757.568182386338, 5765.855853603652, 5778.508826749731, 5780.335261444946, 28672.71327286721, 28673.85663071692, 29872.402551949057, 30082.699173008208, 30505.69494305073, 2261.061659411342, 2301.2290882554366, 2324.2213858357472, 2330.8368414211013, 2332.696749422581, 18822.964736939462, 19373.0, 19411.223551058083, 19423.90288048565, 19466.610667786706, 2188.387251569547, 2189.5949249388914, 2233.6426171812595, 2234.6759719840534, 2274.2836006658017, 164032.0, 166230.71271124054, 173842.0, 174090.0, 202061.98969005208, 13283.535076272285, 14516.927415362856, 15103.924480377527, 15382.692346153239, 15855.58946490377, 20871.165153394, 20888.686669699968, 20895.47761305949, 20928.581428371654, 20954.79045209559, 8561.614727337284, 10418.739531511774, 10428.791424171626, 10461.320014199053, 10467.162626989822, 7918.683252669829, 9796.216302695768, 9808.558614862348, 9827.262955278382, 9850.605975761033, 7520.924790752022, 8176.427650064438, 9288.34981551285, 9349.90650093504, 9394.530273486434, 2555.1695698897934, 2584.2634849067927, 2584.987075064613, 2587.2626301503983, 2676.2372723773633, 923.9353245272766, 924.8705181274627, 933.9213208743912, 969.1277849935115, 979.8775153105862, 19512.804871951383, 21396.679049813953, 22553.323400298017, 22584.0, 22862.0, 951.4766878216954, 983.1446641422009, 1006.6728313298061, 1022.25375475903, 1033.844923261517, 10877.945610271898, 10976.780464390828, 11250.043746281493, 11496.482658280398, 11805.638872225589, 580995.0, 594477.0, 610147.0, 611423.0, 621793.0, 160063.79872402488, 162054.0, 162094.1895290516, 165450.0, 168072.7981800465, 1216.1243904388825, 1251.7559075980096, 1259.5528587351541, 1260.1305099481328, 1262.1228246368685, 19313.4205973821, 19765.912874792026, 23985.0, 25152.0, 25198.74801251964, 63714.0, 73444.8983265262, 74857.0, 76094.0, 77536.2246377529, 74645.253547466, 74809.25190748023, 75171.62414187894, 77700.61149694442, 77984.2201577977, 16500.42248521296, 16948.491545253662, 17026.404075857285, 17207.225674844412, 17322.82677173212, 18439.446816595522, 18442.907785460986, 18515.907420462812, 18517.722234166227, 18802.247910083443, 14356.712865742835, 14708.41166353334, 14797.112173269208, 14985.2507374633, 15218.0, 13117.344132793512, 13245.980210265865, 13291.66770830737, 13858.376373063034, 14255.572332830032, 13462.932685336511, 15535.300911458786, 15628.4530041448, 15647.669948054543, 15661.921690391475, 16607.667846643242, 16667.083310418042, 16700.0, 16807.495775126765, 17060.402885898933, 14853.0, 14883.958122931292, 14907.701845962803, 14967.476138335105, 15000.024998375071], "value": 11999.969654706434, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.108682073704, 1474.0, 1480.68165344451, 1506.8191816981957, 1525.77113432985, 13709.725805483884, 14236.0, 14259.50091746789, 14268.785968210484, 14305.356258968346, 11660.41697915103, 11950.82073768892, 13200.141990770617, 13231.0, 14222.288885555707, 2814.2120206342206, 2859.5273434181413, 2863.0, 2863.241241071107, 2867.1111955293823, 2770.0997175917805, 2802.2714094335415, 2809.929751756212, 2814.226087825854, 2818.957715634267, 2369.5616310982437, 2777.125205560242, 2793.385455199853, 2821.4357128574266, 2826.985865070672, 2651.363672718553, 2756.8208066475713, 2834.9858250708617, 2840.3893162969853, 2843.260752204421, 2796.3708165662765, 2821.266470717628, 2833.914982550527, 2847.886084556615, 2852.429514097189, 13644.044916855824, 13673.589792306248, 13696.93151534236, 13739.450421983109, 13756.17462952216, 7354.963225183893, 7776.572288524129, 7781.805454863617, 7781.8832717509285, 7783.883241751321, 4629.930551041703, 4798.112349215371, 4798.592119669832, 4899.461059283476, 4917.0, 1183.2959389163434, 1369.2400717601727, 1372.876441120294, 1376.0849035391452, 1397.6855207578315, 20843.478913027146, 21052.368428947157, 21058.157673693033, 21076.15695372183, 21078.0, 6695.330466953265, 6747.831304217336, 6755.290694477009, 6773.153355830521, 6848.623325717033, 14536.491222807152, 14548.0, 14549.0, 14558.490452834312, 14591.124532528082, 7443.144038435541, 7540.472166948261, 7589.468737188344, 7590.1650818409935, 7597.164311925684, 6853.074834897325, 6923.5499692519825, 6935.271796461399, 6971.721131154697, 6997.895031574531, 4671.065786842645, 5081.974590127025, 5230.189320655304, 5298.258243845865, 5335.599830012687, 1283.5250957145915, 1455.5924341184486, 1525.9389624414898, 1538.7461068923503, 1557.244736302892, 805.4522924411457, 809.9311558517592, 826.2068414322198, 829.344817594106, 849.222960990699, 7224.313690199423, 7342.559446433231, 7917.0895347034675, 8012.439129260896, 8208.794780130545, 637.3085202555187, 670.8490589617395, 687.1342108942769, 702.4345401951479, 702.6943279673359, 3791.976166435047, 4454.888627784331, 4903.656744027955, 5176.922346164811, 5216.26972223889, 17711.291548337922, 18170.727439088423, 18253.361132360304, 18549.072546372627, 18749.25002999892, 18108.275668973092, 18677.346292879683, 18686.972216528222, 18731.531711707044, 18862.528436788914, 8391.076981532027, 9461.290403219786, 9464.574094165779, 9567.378120422289, 9667.70996870095, 7405.666744996596, 7455.403567714461, 7531.736389226351, 7589.203133670885, 7913.485623434689, 16187.514374568782, 16298.103604301878, 16415.83584164143, 16657.083860387796, 16682.916585416995, 12642.810357844824, 16671.416500422427, 17055.232514536627, 17469.30122795074, 17565.0, 9733.367331123454, 9826.4104153751, 9878.50607469638, 10598.470076496298, 11054.944725276322, 12836.294003829878, 12910.67723306925, 12992.610221693365, 12997.675058123623, 13003.674908127372, 9936.701898943043, 10033.548490317808, 10151.94924025375, 10286.125679317342, 10591.682249532527, 9201.30990175753, 10402.063814256753, 10452.895471045193, 10638.89361106379, 10744.086752625803, 8981.506017169118, 9156.954215228881, 9187.173154415867, 9198.43626583469, 9210.078992100736, 6598.0, 6814.182298124058, 6862.828429289308, 7002.544834585736, 7109.502334836719, 6755.695993680394, 6794.524383293122, 7022.754203602849, 7098.36114749675, 7129.39400150993], "value": 6116.30626158958, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.88187307553875, 436.26053838743303, 445.80607435765427, 476.5948943398112, 477.6346095237144, 6129.7740451909685, 6364.490840732742, 6510.967445162773, 6557.409833115024, 6589.242237142718, 4587.266037434003, 5260.684358938466, 6118.510519158468, 6277.717502712378, 6301.842453938642, 4754.762261886909, 4764.666473346867, 4779.115863565235, 4786.593139583139, 4805.495422980589, 3698.85204591816, 4768.97615511922, 4770.522947705219, 4772.475027746962, 4779.139754844126, 3966.6231707987695, 4734.857954261378, 4747.26417405301, 4769.211545670373, 4782.808687652489, 4125.546189919107, 4547.113312903985, 4746.335513028162, 4751.738654374008, 4759.690620109682, 4158.625723684852, 4161.417401563784, 4173.853915112956, 4185.246655601991, 4206.632844325606, 6163.784267550659, 6187.412195841433, 6193.076504179188, 6196.535259855484, 6224.9688751556405, 5666.490015898547, 5676.231965149294, 5678.659280443187, 5686.772529098831, 5747.655140691572, 5598.664080155163, 6581.440577550914, 6799.354061364212, 6831.897521537181, 6836.897446538306, 3621.800800955946, 4550.362949187116, 4617.746023968681, 4808.990112076433, 4811.831585894477, 6000.309964354112, 7273.38176255019, 7293.927060729431, 7295.89056164158, 7355.227701091362, 4878.9512104878495, 4919.0, 4930.137225985438, 4932.432770231398, 4937.753112344369, 4166.708330416903, 4931.48219436954, 4950.653454258239, 4999.975000125049, 5004.749762511859, 4512.390827238347, 4763.976180119077, 4923.803047878117, 5249.895002099936, 5690.3456102548735, 6863.862722745517, 6915.688794004282, 6922.2731613180895, 6931.202911665223, 6949.270326615632, 1696.821833707443, 1707.4365459398368, 1714.1000974488245, 1736.0, 1889.395393474075, 1137.7895089408487, 1164.6738913104343, 1241.5716577780652, 1346.0241325039465, 1346.9728059270735, 6115.0, 6195.287541932736, 6325.367463253638, 6422.967885160638, 6427.58220715652, 6275.0, 6966.893768356248, 7020.824479388056, 7110.0, 7155.463340249398, 6244.313125556185, 6345.302016778151, 6353.014083380804, 6456.160699109179, 6466.967665161644, 3253.8210398428314, 3401.6598340165783, 3603.072650810629, 3675.3568125577917, 3743.326201283795, 6055.878882422327, 6813.420859226922, 7307.7807665770115, 7319.5242309249725, 7395.482316237919, 6125.519164738288, 7342.486025978237, 7400.706685524998, 7403.51877127985, 7417.666205020787, 7273.636318184175, 7274.272572742895, 7293.124825021031, 7300.138464691469, 7332.8900066499045, 5880.26496687914, 6980.301969802979, 7108.715651373887, 7116.039334689751, 7123.430125590044, 6442.677866106769, 6694.364035416627, 6704.463642908653, 6967.686454109678, 7010.824729381602, 6631.403173714196, 6651.935690289531, 7303.707851685873, 7314.4148468123485, 7343.8898416522725, 4626.953730462652, 4670.953290467051, 4719.244920812654, 4833.564979151894, 4913.926291105565, 6538.640374779433, 7163.498555101093, 7198.316159964795, 7252.694514987142, 7297.671604777692, 6038.879222415615, 6890.793276201722, 6933.221394340198, 6958.408535274356, 6983.546069505466, 5650.406707295675, 5961.612495187799, 6291.96854015727, 6558.934410655832, 6566.800121959197, 6011.677430965181, 6154.815355539341, 6730.798076057726, 6774.187097548326, 6816.250212476524, 6326.652034137983, 6851.623160726208, 6874.621895795779, 6877.552959057845, 6900.89585666291, 5816.982028144973, 6628.177251255943, 6700.396964273239, 6705.765298214539, 6758.93241067583], "value": 5041.4708952156425, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [833.4540875726401, 840.8276303357811, 841.3185319890885, 864.5302984925628, 869.4088020146299, 14011.36948837301, 16682.666346673075, 16684.915480493775, 16892.493225203234, 16931.98408095515, 17056.0, 17495.125243737795, 18571.0, 18619.34832280867, 18648.72026919594, 1001.8547310639952, 1011.1051719228462, 1028.9742756431074, 1029.2075102171323, 1031.7575369788094, 1026.4303311662024, 1039.6621098143096, 1040.2042437535292, 1040.33938449085, 1047.3820445936897, 950.9381890177151, 995.4176806568166, 1062.702443315869, 1063.702163394247, 1071.8820929697765, 1045.513836066229, 1054.520193312041, 1058.8941105889348, 1060.4114716332433, 1061.6708820265703, 626.9623822570614, 760.6691089376084, 764.5259938837956, 771.155584634824, 771.5332224004509, 17036.2333694984, 17129.571760705956, 17138.657226855517, 17340.653186936317, 17381.391651292142, 17404.0, 17597.824021759876, 17739.556511087325, 17802.55493612657, 17830.643387132186, 14950.0, 15017.098974061482, 15104.018238814442, 15122.924385378114, 15141.470048548248, 3520.3487354839435, 3537.982310088433, 3568.8750893718857, 3572.964270357289, 3826.9808650956847, 112701.43649281701, 133235.0, 136473.0, 154044.2297788515, 156281.65577516233, 13666.52167174144, 13748.65628359299, 14008.789868151986, 14016.439342426393, 14047.0, 25957.961681532906, 27047.86476067607, 27273.86363068172, 27307.726922730915, 27382.315442113708, 8007.919920800718, 8101.311388531923, 8192.959035204905, 8267.338612910953, 8274.420790544724, 3916.1580260244186, 3956.951407876915, 3969.226000929791, 3988.8205030773684, 3993.4409182714444, 4549.454065512094, 4668.95331046692, 4719.126961512131, 4783.186858234039, 5059.645824792303, 857.8798968144465, 894.7226359828474, 899.1727610598265, 1067.51961617271, 1075.5052675769077, 584.3951510186936, 637.3626373626373, 640.6828619833212, 682.4642655515397, 697.9720811167497, 11422.31466112036, 13134.474621015052, 13939.721205575832, 13949.16305021702, 14026.368813403422, 633.2369494758805, 636.9808905732835, 650.3821369698805, 657.3131078023476, 672.5695554844904, 5391.595630327663, 5657.915131273035, 5672.347680016768, 5775.971120144372, 5981.6710080945095, 129850.0, 139836.0, 140599.0, 141440.0, 142988.0, 113400.0, 115216.0, 116680.0, 120369.7963020376, 123766.0, 98444.0, 102284.48857755664, 104140.0, 104548.0, 105538.4169237462, 12030.458629361525, 12419.379031048593, 12439.06706996997, 12545.0, 12701.618951431472, 45566.493842838965, 45752.771236143606, 45900.31149532693, 46621.0, 47417.52582474131, 85780.0, 86315.0, 88151.11848881685, 88495.0, 89192.0, 14533.563993080224, 17015.0, 17848.28606855763, 18034.2786288547, 18069.548261293046, 18591.814081859007, 19183.52041198981, 19301.710474342613, 19373.128209230337, 19487.12307946174, 17144.82855171482, 17802.0, 17888.821111788715, 17950.730739039187, 18190.272389104815, 16973.745393818856, 17517.299308027537, 17743.201555929754, 18098.185581648595, 18136.818631813512, 16918.984860908386, 17498.300067997137, 17567.12164391801, 17827.554311141805, 17971.640567188846, 11184.440777961232, 11611.88388116108, 11849.289042657469, 11965.521379144737, 12542.93728531388, 11219.831702524305, 11906.047516198856, 12864.326587233074, 13452.93273533626, 13738.969577281545], "value": 8970.087761955197, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1511.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 116.0, 105.0, 117.0, 117.0, 115.0, 115.0, 103.0, 114.0, 117.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 111.0, 161.0, 148.0, 112.0, 125.0, 113.0, 148.0, 139.0, 107.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [228.0, 280.0, 200.0, 197.0, 194.0, 196.0, 196.0, 198.0, 194.0, 196.0], "value": 207.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 107.0, 117.0, 142.0, 86.0, 90.0, 84.0, 73.0, 139.0, 73.0], "value": 97.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 115.0, 69.0, 69.0, 75.0, 109.0, 68.0, 70.0, 116.0], "value": 83.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 89.0, 89.0, 89.0, 89.0, 88.0, 89.0, 88.0, 92.0], "value": 89.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 96.0, 96.0, 106.0, 149.0, 95.0, 125.0, 97.0], "value": 105.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 90.0, 91.0, 93.0, 90.0, 110.0, 89.0, 90.0, 86.0], "value": 92.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 51.0, 54.0, 52.0, 53.0, 53.0, 53.0, 54.0, 53.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 56.0, 55.0, 63.0, 62.0, 88.0, 49.0, 50.0, 58.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 79.0, 80.0, 81.0, 79.0, 79.0, 80.0, 80.0, 79.0, 81.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 135.0, 133.0, 134.0, 133.0, 138.0, 135.0, 137.0, 142.0, 160.0], "value": 138.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 179.0, 175.0, 178.0, 201.0, 218.0, 201.0, 176.0, 202.0, 232.0], "value": 193.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 62.0, 65.0, 67.0, 65.0, 68.0, 66.0, 78.0, 77.0, 85.0], "value": 70.1, "unit": "score"}]}]} 11:24:21 INFO - Return code: 0 11:24:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:24:21 INFO - # TBPL SUCCESS # 11:24:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:24:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:24:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:24:21 INFO - 2017-06-14 11:24:21.940 screenresolution[1668:10960] starting screenresolution argv=screenresolution get 11:24:21 INFO - 2017-06-14 11:24:21.942 screenresolution[1668:10960] Display 0: 1600x1200x32@60 11:24:21 INFO - 2017-06-14 11:24:21.948 screenresolution[1669:10963] starting screenresolution argv=screenresolution list 11:24:21 INFO - Available Modes on Display 0 11:24:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:24:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:24:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:24:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:24:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:24:22 INFO - Intel Iris: 11:24:22 INFO - Chipset Model: Intel Iris 11:24:22 INFO - Type: GPU 11:24:22 INFO - Bus: Built-In 11:24:22 INFO - VRAM (Dynamic, Max): 1536 MB 11:24:22 INFO - Vendor: Intel (0x8086) 11:24:22 INFO - Device ID: 0x0a2e 11:24:22 INFO - Revision ID: 0x0009 11:24:22 INFO - Displays: 11:24:22 INFO - Display: 11:24:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:24:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:24:22 INFO - Display Serial Number: 1600x1200 60 11:24:22 INFO - Main Display: Yes 11:24:22 INFO - Mirror: Off 11:24:22 INFO - Online: Yes 11:24:22 INFO - Rotation: Supported 11:24:22 INFO - Return code: 0 11:24:22 INFO - Running post-action listener: _resource_record_post_action 11:24:22 INFO - [mozharness: 2017-06-14 18:24:22.183101Z] Finished run-tests step (success) 11:24:22 INFO - Running post-run listener: _resource_record_post_run 11:24:22 INFO - Total resource usage - Wall time: 1253s; CPU: 53.0%; Read bytes: 40835584; Write bytes: 559198208; Read time: 269; Write time: 1718 11:24:22 INFO - TinderboxPrint: CPU usage
53.0% 11:24:22 INFO - TinderboxPrint: I/O read bytes / time
40,835,584 / 269 11:24:22 INFO - TinderboxPrint: I/O write bytes / time
559,198,208 / 1,718 11:24:22 INFO - TinderboxPrint: CPU idle
2,356.6 (47.0%) 11:24:22 INFO - TinderboxPrint: CPU system
1,014.8 (20.2%) 11:24:22 INFO - TinderboxPrint: CPU user
1,642.2 (32.8%) 11:24:22 INFO - TinderboxPrint: Swap in / out
283,836,416 / 0 11:24:22 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 232083968; Write bytes: 228198400; Read time: 16917; Write time: 468 11:24:22 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:24:22 INFO - run-tests - Wall time: 1233s; CPU: 53.0%; Read bytes: 38791680; Write bytes: 311683072; Read time: 248; Write time: 1203 11:24:22 INFO - Running post-run listener: _upload_blobber_files 11:24:22 INFO - Blob upload gear active. 11:24:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:24:22 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:24:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:24:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:24:23 INFO - (blobuploader) - INFO - Open directory for files ... 11:24:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:24:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:24:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:24:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:24:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:24:24 INFO - (blobuploader) - INFO - Done attempting. 11:24:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:24:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:24:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:24:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:24:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:24:25 INFO - (blobuploader) - INFO - Done attempting. 11:24:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:24:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:24:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:24:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:24:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:24:25 INFO - (blobuploader) - INFO - Done attempting. 11:24:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:24:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:24:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:24:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:24:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:24:27 INFO - (blobuploader) - INFO - Done attempting. 11:24:27 INFO - (blobuploader) - INFO - Iteration through files over. 11:24:27 INFO - Return code: 0 11:24:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:24:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:24:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a9ca74029d86b4912da775b2e3658113e8451dc7e1e4d120af7a2d21ea5cad1c172339063e91407dd334f4733fc10275755ecd8529edb5c058e46a4e3341004", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/277baeab124693823d08c84b0f25d1d040af2ced6eff27f0ceb35e4139f568880ed07632b68b1011b969d657e6b5f30e52393f2628a0d778c7da0e9630a29211", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97c05cadcb3d066005f297435a329824477f9e5ea48c255471ecb58b7d9dce35bb4aedd94e04d69f96b339e4ccbb8c2a06274ddec164a4b0fe8b36f2a35d5c45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac52125b58d5e253898f3c0b3803c397819180db492d81404711cdd723718a48119b3c2ec6b1e9873ce5375fdf8aa4dbfcd6bf02e3aa724bd812a1ded35bd71d"} 11:24:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:24:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:24:27 INFO - Contents: 11:24:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a9ca74029d86b4912da775b2e3658113e8451dc7e1e4d120af7a2d21ea5cad1c172339063e91407dd334f4733fc10275755ecd8529edb5c058e46a4e3341004", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/277baeab124693823d08c84b0f25d1d040af2ced6eff27f0ceb35e4139f568880ed07632b68b1011b969d657e6b5f30e52393f2628a0d778c7da0e9630a29211", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97c05cadcb3d066005f297435a329824477f9e5ea48c255471ecb58b7d9dce35bb4aedd94e04d69f96b339e4ccbb8c2a06274ddec164a4b0fe8b36f2a35d5c45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac52125b58d5e253898f3c0b3803c397819180db492d81404711cdd723718a48119b3c2ec6b1e9873ce5375fdf8aa4dbfcd6bf02e3aa724bd812a1ded35bd71d"} 11:24:27 INFO - Running post-run listener: copy_logs_to_upload_dir 11:24:27 INFO - Copying logs to upload dir... 11:24:27 INFO - mkdir: /builds/slave/test/build/upload/logs 11:24:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1335.352372 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2017-06-14 11:24:27.856472) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 11:24:27.860207) ========= 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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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/8a9ca74029d86b4912da775b2e3658113e8451dc7e1e4d120af7a2d21ea5cad1c172339063e91407dd334f4733fc10275755ecd8529edb5c058e46a4e3341004", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/277baeab124693823d08c84b0f25d1d040af2ced6eff27f0ceb35e4139f568880ed07632b68b1011b969d657e6b5f30e52393f2628a0d778c7da0e9630a29211", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97c05cadcb3d066005f297435a329824477f9e5ea48c255471ecb58b7d9dce35bb4aedd94e04d69f96b339e4ccbb8c2a06274ddec164a4b0fe8b36f2a35d5c45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac52125b58d5e253898f3c0b3803c397819180db492d81404711cdd723718a48119b3c2ec6b1e9873ce5375fdf8aa4dbfcd6bf02e3aa724bd812a1ded35bd71d"} build_url:https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010843 build_url: 'https://queue.taskcluster.net/v1/task/R2iUXJpIRimjsP5bQyRbuA/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/8a9ca74029d86b4912da775b2e3658113e8451dc7e1e4d120af7a2d21ea5cad1c172339063e91407dd334f4733fc10275755ecd8529edb5c058e46a4e3341004", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/277baeab124693823d08c84b0f25d1d040af2ced6eff27f0ceb35e4139f568880ed07632b68b1011b969d657e6b5f30e52393f2628a0d778c7da0e9630a29211", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97c05cadcb3d066005f297435a329824477f9e5ea48c255471ecb58b7d9dce35bb4aedd94e04d69f96b339e4ccbb8c2a06274ddec164a4b0fe8b36f2a35d5c45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac52125b58d5e253898f3c0b3803c397819180db492d81404711cdd723718a48119b3c2ec6b1e9873ce5375fdf8aa4dbfcd6bf02e3aa724bd812a1ded35bd71d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 11:24:27.891939) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 11:24:27.892273) ========= 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.jQ5fQRZYTP/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.KXHxoqjvFs/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.005069 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 11:24:27.940746) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 11:24:27.941101) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 11:24:27.941397) ========= ========= Total master_lag: 0.73 =========