builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0383 starttime: 1497365240.26 results: success (0) buildid: 20170613064334 builduid: c0e0c323e3b44eafbfab1d236bc5691c revision: e8dd9736241ea1989feaf3c85a71593c8a6f3bbb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 07:47:20.256001) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 07:47:20.256416) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 07:47:20.256686) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 07:47:20.279803) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 07:47:20.280111) ========= 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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-13 07:47:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.11M=0.001s 2017-06-13 07:47:20 (8.11 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104337 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 07:47:20.410841) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 07:47:20.411317) ========= 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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.021018 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 07:47:20.462049) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 07:47:20.462762) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e8dd9736241ea1989feaf3c85a71593c8a6f3bbb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e8dd9736241ea1989feaf3c85a71593c8a6f3bbb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.r0B0gBnr19/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.YZ5cAK0mwj/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-13 07:47:20,541 truncating revision to first 12 chars 2017-06-13 07:47:20,542 Setting DEBUG logging. 2017-06-13 07:47:20,542 attempt 1/10 2017-06-13 07:47:20,542 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e8dd9736241e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 07:47:21,294 unpacking tar archive at: mozilla-beta-e8dd9736241e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.015614 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 07:47:21.503648) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 07:47:21.503987) ========= script_repo_revision: e8dd9736241ea1989feaf3c85a71593c8a6f3bbb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 07:47:21.504372) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 07:47:21.504634) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 07:47:21.523656) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-13 07:47:21.524187) ========= /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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:47:21 INFO - MultiFileLogger online at 20170613 07:47:21 in /builds/slave/test 07:47:21 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 07:47:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:47:21 INFO - {'append_to_log': False, 07:47:21 INFO - 'base_work_dir': '/builds/slave/test', 07:47:21 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:47:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:47:21 INFO - 'branch': 'Mozilla-Beta', 07:47:21 INFO - 'buildbot_json_path': 'buildprops.json', 07:47:21 INFO - 'config_files': ('talos/mac_config.py',), 07:47:21 INFO - 'default_actions': ('clobber', 07:47:21 INFO - 'read-buildbot-config', 07:47:21 INFO - 'download-and-extract', 07:47:21 INFO - 'populate-webroot', 07:47:21 INFO - 'create-virtualenv', 07:47:21 INFO - 'install', 07:47:21 INFO - 'setup-mitmproxy', 07:47:21 INFO - 'run-tests'), 07:47:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:47:21 INFO - 'download_minidump_stackwalk': True, 07:47:21 INFO - 'download_symbols': 'ondemand', 07:47:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:47:21 INFO - 'tooltool.py': '/tools/tooltool.py', 07:47:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:47:21 INFO - '/tools/misc-python/virtualenv.py')}, 07:47:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:47:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:47:21 INFO - 'gecko_profile': False, 07:47:21 INFO - 'gecko_profile_interval': 0, 07:47:21 INFO - 'installer_path': 'installer.exe', 07:47:21 INFO - 'log_level': 'info', 07:47:21 INFO - 'log_name': 'talos', 07:47:21 INFO - 'log_to_console': True, 07:47:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:47:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:47:21 INFO - 'opt_config_files': (), 07:47:21 INFO - 'pip_index': False, 07:47:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:47:21 INFO - 'cmd': ('bash', 07:47:21 INFO - '-c', 07:47:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:47:21 INFO - 'enabled': True, 07:47:21 INFO - 'halt_on_failure': False, 07:47:21 INFO - 'name': 'check_screen_resolution'},), 07:47:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:47:21 INFO - 'cmd': ('bash', 07:47:21 INFO - '-c', 07:47:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:47:21 INFO - 'enabled': True, 07:47:21 INFO - 'halt_on_failure': False, 07:47:21 INFO - 'name': 'check_screen_resolution'},), 07:47:21 INFO - 'run_cmd_checks_enabled': True, 07:47:21 INFO - 'suite': 'dromaeojs-e10s', 07:47:21 INFO - 'system_bits': '32', 07:47:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:47:21 INFO - 'title': 't-yosemite-r7-0383', 07:47:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:47:21 INFO - 'use_talos_json': True, 07:47:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:47:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:47:21 INFO - 'work_dir': 'build'} 07:47:21 INFO - [mozharness: 2017-06-13 14:47:21.727214Z] Running clobber step. 07:47:21 INFO - Running pre-action listener: _resource_record_pre_action 07:47:21 INFO - Running main action method: clobber 07:47:21 INFO - rmtree: /builds/slave/test/build 07:47:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:47:22 INFO - Running post-action listener: _resource_record_post_action 07:47:22 INFO - [mozharness: 2017-06-13 14:47:22.360656Z] Finished clobber step (success) 07:47:22 INFO - [mozharness: 2017-06-13 14:47:22.360835Z] Running read-buildbot-config step. 07:47:22 INFO - Running pre-action listener: _resource_record_pre_action 07:47:22 INFO - Running main action method: read_buildbot_config 07:47:22 INFO - Using buildbot properties: 07:47:22 INFO - { 07:47:22 INFO - "project": "", 07:47:22 INFO - "product": "firefox", 07:47:22 INFO - "script_repo_revision": "production", 07:47:22 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 07:47:22 INFO - "repository": "", 07:47:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 07:47:22 INFO - "buildid": "20170613064334", 07:47:22 INFO - "pgo_build": "False", 07:47:22 INFO - "basedir": "/builds/slave/test", 07:47:22 INFO - "buildnumber": 1, 07:47:22 INFO - "slavename": "t-yosemite-r7-0383", 07:47:22 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:47:22 INFO - "platform": "yosemite_r7_devedition", 07:47:22 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 07:47:22 INFO - "branch": "mozilla-beta", 07:47:22 INFO - "repo_path": "releases/mozilla-beta", 07:47:22 INFO - "revision": "e8dd9736241ea1989feaf3c85a71593c8a6f3bbb", 07:47:22 INFO - "stage_platform": "macosx64-devedition", 07:47:22 INFO - "builduid": "c0e0c323e3b44eafbfab1d236bc5691c", 07:47:22 INFO - "slavebuilddir": "test" 07:47:22 INFO - } 07:47:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 07:47:22 INFO - Running post-action listener: _resource_record_post_action 07:47:22 INFO - [mozharness: 2017-06-13 14:47:22.364910Z] Finished read-buildbot-config step (success) 07:47:22 INFO - [mozharness: 2017-06-13 14:47:22.365019Z] Running download-and-extract step. 07:47:22 INFO - Running pre-action listener: _resource_record_pre_action 07:47:22 INFO - Running main action method: download_and_extract 07:47:22 INFO - mkdir: /builds/slave/test/build/tests 07:47:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:47:22 INFO - trying https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 07:47:22 INFO - Downloading https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/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 07:47:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/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 07:47:23 INFO - Downloaded 1290 bytes. 07:47:23 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 07:47:23 INFO - Using the following test package requirements: 07:47:23 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:47:23 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 07:47:23 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 07:47:23 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:47:23 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 07:47:23 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:47:23 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 07:47:23 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 07:47:23 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:47:23 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 07:47:23 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 07:47:23 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:47:23 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 07:47:23 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:47:23 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 07:47:23 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:47:23 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 07:47:23 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 07:47:23 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 07:47:23 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 07:47:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 07:47:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 07:47:23 INFO - Fetch https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 07:47:25 INFO - Content-Length response header: 59547931 07:47:25 INFO - Bytes received: 59547931 07:47:43 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 07:47:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 07:47:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 07:47:43 INFO - Fetch https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 07:47:45 INFO - Content-Length response header: 59547931 07:47:45 INFO - Bytes received: 59547931 07:48:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 07:48:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 07:48:02 INFO - Fetch https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 07:48:03 INFO - Content-Length response header: 14450018 07:48:03 INFO - Bytes received: 14450018 07:48: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')]} 07:48:04 INFO - trying https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 07:48:04 INFO - Downloading https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 07:48:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/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 07:48:06 INFO - Downloaded 66875463 bytes. 07:48:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 07:48:06 INFO - mkdir: /builds/slave/test/properties 07:48:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:48:06 INFO - Writing to file /builds/slave/test/properties/build_url 07:48:06 INFO - Contents: 07:48:06 INFO - build_url:https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 07:48:06 INFO - Running post-action listener: _resource_record_post_action 07:48:06 INFO - Running post-action listener: set_extra_try_arguments 07:48:06 INFO - [mozharness: 2017-06-13 14:48:06.784134Z] Finished download-and-extract step (success) 07:48:06 INFO - [mozharness: 2017-06-13 14:48:06.784288Z] Running populate-webroot step. 07:48:06 INFO - Running pre-action listener: _resource_record_pre_action 07:48:06 INFO - Running main action method: populate_webroot 07:48:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:48:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:48:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:48:06 INFO - '--tppagecycles', 07:48:06 INFO - '7'], 07:48:06 INFO - 'tests': ['tsvgm']}}, 07:48:06 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:48:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:48:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:48:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:48:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:48:06 INFO - 'talos_options': ['--disable-e10s'], 07:48:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:48:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:48:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:48:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:48:06 INFO - 'talos_options': ['--disable-e10s'], 07:48:06 INFO - 'tests': ['damp', 'tps']}, 07:48:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:48:06 INFO - 'tests': ['damp', 'tps']}, 07:48:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:48:06 INFO - 'tests': ['dromaeo_dom']}, 07:48:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:48:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:48:06 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:48:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:48:06 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:48:06 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:48:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:48:06 INFO - 'tests': ['a11yr', 07:48:06 INFO - 'ts_paint', 07:48:06 INFO - 'tpaint', 07:48:06 INFO - 'sessionrestore', 07:48:06 INFO - 'sessionrestore_no_auto_restore', 07:48:06 INFO - 'tabpaint']}, 07:48:06 INFO - 'other-e10s': {'tests': ['a11yr', 07:48:06 INFO - 'ts_paint', 07:48:06 INFO - 'tpaint', 07:48:06 INFO - 'sessionrestore', 07:48:06 INFO - 'sessionrestore_no_auto_restore', 07:48:06 INFO - 'tabpaint']}, 07:48:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:48:06 INFO - 'ts_paint', 07:48:06 INFO - 'tpaint', 07:48:06 INFO - 'sessionrestore', 07:48:06 INFO - 'sessionrestore_no_auto_restore', 07:48:06 INFO - 'tabpaint']}, 07:48:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:48:06 INFO - 'ts_paint', 07:48:06 INFO - 'tpaint', 07:48:06 INFO - 'sessionrestore', 07:48:06 INFO - 'sessionrestore_no_auto_restore', 07:48:06 INFO - 'tabpaint']}, 07:48:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:48:06 INFO - 'tests': ['a11yr', 07:48:06 INFO - 'ts_paint', 07:48:06 INFO - 'tpaint', 07:48:06 INFO - 'sessionrestore', 07:48:06 INFO - 'sessionrestore_no_auto_restore', 07:48:06 INFO - 'tabpaint']}, 07:48:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:48:06 INFO - 'tests': ['a11yr', 07:48:06 INFO - 'ts_paint', 07:48:06 INFO - 'tpaint', 07:48:06 INFO - 'sessionrestore', 07:48:06 INFO - 'sessionrestore_no_auto_restore', 07:48:06 INFO - 'tabpaint']}, 07:48:06 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:48:06 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:48:06 INFO - 'bloom_basic_ref']}, 07:48:06 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:48:06 INFO - 'talos_options': ['--mitmproxy', 07:48:06 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:48:06 INFO - '--firstNonBlankPaint'], 07:48:06 INFO - 'tests': ['quantum_pageload_google', 07:48:06 INFO - 'quantum_pageload_youtube', 07:48:06 INFO - 'quantum_pageload_amazon', 07:48:06 INFO - 'quantum_pageload_facebook']}, 07:48:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:48:06 INFO - 'tests': ['tsvgx', 07:48:06 INFO - 'tsvgr_opacity', 07:48:06 INFO - 'tart', 07:48:06 INFO - 'tscrollx', 07:48:06 INFO - 'cart', 07:48:06 INFO - 'tsvg_static']}, 07:48:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:48:06 INFO - 'tsvgr_opacity', 07:48:06 INFO - 'tart', 07:48:06 INFO - 'tscrollx', 07:48:06 INFO - 'cart', 07:48:06 INFO - 'tsvg_static']}, 07:48:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:48:06 INFO - 'talos_options': ['--disable-e10s'], 07:48:06 INFO - 'tests': ['tp5o']}, 07:48:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:48:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:48:06 INFO - 'talos_options': ['--disable-e10s', 07:48:06 INFO - '--xperf_path', 07:48:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:48:06 INFO - 'tests': ['tp5n']}, 07:48:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:48:06 INFO - 'talos_options': ['--xperf_path', 07:48:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:48:06 INFO - 'tests': ['tp5n']}}, 07:48:06 INFO - 'talos.zip': {'path': '', 07:48:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:48:06 INFO - Running post-action listener: _resource_record_post_action 07:48:06 INFO - [mozharness: 2017-06-13 14:48:06.805055Z] Finished populate-webroot step (success) 07:48:06 INFO - [mozharness: 2017-06-13 14:48:06.805179Z] Running create-virtualenv step. 07:48:06 INFO - Running pre-action listener: _resource_record_pre_action 07:48:06 INFO - Running main action method: create_virtualenv 07:48:06 INFO - Creating virtualenv /builds/slave/test/build/venv 07:48:06 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 07:48:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:48:06 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:48:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:48:06 INFO - Using real prefix '/tools/python27' 07:48:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:48:07 INFO - Installing distribute.............................................................................................................................................................................................done. 07:48:10 INFO - Installing pip.................done. 07:48:10 INFO - Return code: 0 07:48:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:48:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:48:10 INFO - Reading from file tmpfile_stdout 07:48:10 INFO - Output received: 07:48:10 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) 07:48:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:48: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')]} 07:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48: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')]} 07:48:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa65b47ecd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb50d30>, '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 0x10cb1acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb41be8>, '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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:48: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', 'pip>=1.5'] in /builds/slave/test/build 07:48: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 pip>=1.5 07:48:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r0B0gBnr19/Render', 07:48:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:10 INFO - 'HOME': '/Users/cltbld', 07:48:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:10 INFO - 'LOGNAME': 'cltbld', 07:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:10 INFO - 'MOZ_NO_REMOTE': '1', 07:48:10 INFO - 'NO_EM_RESTART': '1', 07:48:10 INFO - 'PAGER': '/bin/cat', 07:48:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:10 INFO - 'PWD': '/builds/slave/test', 07:48:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:10 INFO - 'SHELL': '/bin/bash', 07:48:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YZ5cAK0mwj/Listeners', 07:48:10 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:48:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:10 INFO - 'USER': 'cltbld', 07:48:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:10 INFO - 'XPC_FLAGS': '0x0', 07:48:10 INFO - 'XPC_SERVICE_NAME': '0', 07:48:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:11 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 07:48:11 INFO - Cleaning up... 07:48:11 INFO - Return code: 0 07:48:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cdfac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa65b47ecd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb50d30>, '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 0x10cb1acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb41be8>, '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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:48:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:48:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:48:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r0B0gBnr19/Render', 07:48:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:11 INFO - 'HOME': '/Users/cltbld', 07:48:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:11 INFO - 'LOGNAME': 'cltbld', 07:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:11 INFO - 'MOZ_NO_REMOTE': '1', 07:48:11 INFO - 'NO_EM_RESTART': '1', 07:48:11 INFO - 'PAGER': '/bin/cat', 07:48:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:11 INFO - 'PWD': '/builds/slave/test', 07:48:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:11 INFO - 'SHELL': '/bin/bash', 07:48:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YZ5cAK0mwj/Listeners', 07:48:11 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:48:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:11 INFO - 'USER': 'cltbld', 07:48:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:11 INFO - 'XPC_FLAGS': '0x0', 07:48:11 INFO - 'XPC_SERVICE_NAME': '0', 07:48:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:11 INFO - Downloading/unpacking psutil>=3.1.1 07:48: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 07:48: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 07:48: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 07:48: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 07:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:48:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:48:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:48:13 INFO - Installing collected packages: psutil 07:48:13 INFO - Running setup.py install for psutil 07:48:13 INFO - building 'psutil._psutil_osx' extension 07:48:13 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 07:48:13 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 07:48:13 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 07:48:13 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 07:48:13 INFO - building 'psutil._psutil_posix' extension 07:48:13 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 07:48:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:48:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:48:13 INFO - ^ 07:48:13 INFO - 1 warning generated. 07:48:13 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 07:48:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:48:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:48:13 INFO - Successfully installed psutil 07:48:13 INFO - Cleaning up... 07:48:14 INFO - Return code: 0 07:48:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa65b47ecd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb50d30>, '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 0x10cb1acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb41be8>, '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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:48:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:48:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r0B0gBnr19/Render', 07:48:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:14 INFO - 'HOME': '/Users/cltbld', 07:48:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:14 INFO - 'LOGNAME': 'cltbld', 07:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:14 INFO - 'MOZ_NO_REMOTE': '1', 07:48:14 INFO - 'NO_EM_RESTART': '1', 07:48:14 INFO - 'PAGER': '/bin/cat', 07:48:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:14 INFO - 'PWD': '/builds/slave/test', 07:48:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:14 INFO - 'SHELL': '/bin/bash', 07:48:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YZ5cAK0mwj/Listeners', 07:48:14 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:48:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:14 INFO - 'USER': 'cltbld', 07:48:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:14 INFO - 'XPC_FLAGS': '0x0', 07:48:14 INFO - 'XPC_SERVICE_NAME': '0', 07:48:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:48:14 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 07:48:14 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 07:48:14 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 07:48:14 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 07:48:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:48:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:48:15 INFO - Installing collected packages: mozsystemmonitor 07:48:15 INFO - Running setup.py install for mozsystemmonitor 07:48:15 INFO - Successfully installed mozsystemmonitor 07:48:15 INFO - Cleaning up... 07:48:16 INFO - Return code: 0 07:48:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:48: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')]} 07:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48: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')]} 07:48:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:16 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 0x10cdfac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa65b47ecd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb50d30>, '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 0x10cb1acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb41be8>, '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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:48:16 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 07:48:16 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 07:48:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r0B0gBnr19/Render', 07:48:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:16 INFO - 'HOME': '/Users/cltbld', 07:48:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:16 INFO - 'LOGNAME': 'cltbld', 07:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:16 INFO - 'MOZ_NO_REMOTE': '1', 07:48:16 INFO - 'NO_EM_RESTART': '1', 07:48:16 INFO - 'PAGER': '/bin/cat', 07:48:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:16 INFO - 'PWD': '/builds/slave/test', 07:48:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:16 INFO - 'SHELL': '/bin/bash', 07:48:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YZ5cAK0mwj/Listeners', 07:48:16 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:48:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:16 INFO - 'USER': 'cltbld', 07:48:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:16 INFO - 'XPC_FLAGS': '0x0', 07:48:16 INFO - 'XPC_SERVICE_NAME': '0', 07:48:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:16 INFO - Downloading/unpacking jsonschema==2.5.1 07:48:16 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 07:48:16 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 07:48:16 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 07:48:16 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 07:48:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:48:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:48:17 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 07:48:17 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 07:48:17 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 07:48:17 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 07:48:17 INFO - Downloading functools32-3.2.3-2.tar.gz 07:48:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:48:17 INFO - warning: no files found matching '*.txt' 07:48:18 INFO - no previously-included directories found matching 'build' 07:48:18 INFO - no previously-included directories found matching 'dist' 07:48:18 INFO - no previously-included directories found matching '.git*' 07:48:18 INFO - Installing collected packages: jsonschema, functools32 07:48:18 INFO - Running setup.py install for functools32 07:48:18 INFO - warning: no files found matching '*.txt' 07:48:18 INFO - no previously-included directories found matching 'build' 07:48:18 INFO - no previously-included directories found matching 'dist' 07:48:18 INFO - no previously-included directories found matching '.git*' 07:48:18 INFO - Successfully installed jsonschema functools32 07:48:18 INFO - Cleaning up... 07:48:18 INFO - Return code: 0 07:48:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:48:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cdfac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa65b47ecd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb50d30>, '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 0x10cb1acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb41be8>, '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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:48:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:48:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:48:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r0B0gBnr19/Render', 07:48:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:19 INFO - 'HOME': '/Users/cltbld', 07:48:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:19 INFO - 'LOGNAME': 'cltbld', 07:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:19 INFO - 'MOZ_NO_REMOTE': '1', 07:48:19 INFO - 'NO_EM_RESTART': '1', 07:48:19 INFO - 'PAGER': '/bin/cat', 07:48:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:19 INFO - 'PWD': '/builds/slave/test', 07:48:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:19 INFO - 'SHELL': '/bin/bash', 07:48:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YZ5cAK0mwj/Listeners', 07:48:19 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:48:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:19 INFO - 'USER': 'cltbld', 07:48:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:19 INFO - 'XPC_FLAGS': '0x0', 07:48:19 INFO - 'XPC_SERVICE_NAME': '0', 07:48:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:48:19 INFO - Cleaning up... 07:48:19 INFO - Return code: 0 07:48:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:48:19 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')]} 07:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:19 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')]} 07:48:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cdfac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa65b47ecd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb50d30>, '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 0x10cb1acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb41be8>, '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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:48:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:48:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:48:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r0B0gBnr19/Render', 07:48:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:19 INFO - 'HOME': '/Users/cltbld', 07:48:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:19 INFO - 'LOGNAME': 'cltbld', 07:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:19 INFO - 'MOZ_NO_REMOTE': '1', 07:48:19 INFO - 'NO_EM_RESTART': '1', 07:48:19 INFO - 'PAGER': '/bin/cat', 07:48:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:19 INFO - 'PWD': '/builds/slave/test', 07:48:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:19 INFO - 'SHELL': '/bin/bash', 07:48:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YZ5cAK0mwj/Listeners', 07:48:19 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:48:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:19 INFO - 'USER': 'cltbld', 07:48:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:19 INFO - 'XPC_FLAGS': '0x0', 07:48:19 INFO - 'XPC_SERVICE_NAME': '0', 07:48:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:20 INFO - Downloading/unpacking blobuploader==1.2.4 07:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:48:21 INFO - Downloading blobuploader-1.2.4.tar.gz 07:48:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:48:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:48:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:48:22 INFO - Downloading docopt-0.6.1.tar.gz 07:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:48:22 INFO - Installing collected packages: blobuploader, requests, docopt 07:48:22 INFO - Running setup.py install for blobuploader 07:48:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:48:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:48:23 INFO - Running setup.py install for requests 07:48:23 INFO - Running setup.py install for docopt 07:48:23 INFO - Successfully installed blobuploader requests docopt 07:48:23 INFO - Cleaning up... 07:48:23 INFO - Return code: 0 07:48:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:23 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 0x10cdfac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa65b47ecd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb50d30>, '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 0x10cb1acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb41be8>, '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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:48:23 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 07:48:23 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 07:48:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r0B0gBnr19/Render', 07:48:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:23 INFO - 'HOME': '/Users/cltbld', 07:48:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:23 INFO - 'LOGNAME': 'cltbld', 07:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:23 INFO - 'MOZ_NO_REMOTE': '1', 07:48:23 INFO - 'NO_EM_RESTART': '1', 07:48:23 INFO - 'PAGER': '/bin/cat', 07:48:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:23 INFO - 'PWD': '/builds/slave/test', 07:48:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:23 INFO - 'SHELL': '/bin/bash', 07:48:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YZ5cAK0mwj/Listeners', 07:48:23 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:48:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:23 INFO - 'USER': 'cltbld', 07:48:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:23 INFO - 'XPC_FLAGS': '0x0', 07:48:23 INFO - 'XPC_SERVICE_NAME': '0', 07:48:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:48:24 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-mFWwFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:48:24 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-24ryGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:48:24 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-O_Gix4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:48:24 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Oy4VTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:48:24 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-JE7lgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:48:24 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-FndAyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:48:24 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-y14Iy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:48:24 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-x4L2XR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:48:24 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-2EFbOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:48:24 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-h8BxzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:48:25 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-ZO6MYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:48:25 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-EZK9Nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:48:25 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-ljCRu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:48:25 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-coP2Gy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:48:25 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-jGahQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:48:25 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-qJFsGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:48:25 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Jn8fVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:48:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:48:25 INFO - Running setup.py install for manifestparser 07:48:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:48:25 INFO - Running setup.py install for mozcrash 07:48:26 INFO - Running setup.py install for mozdebug 07:48:26 INFO - Running setup.py install for mozdevice 07:48:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:48:26 INFO - Running setup.py install for mozfile 07:48:26 INFO - Running setup.py install for mozhttpd 07:48:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:48:26 INFO - Running setup.py install for mozinfo 07:48:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:48:26 INFO - Running setup.py install for mozInstall 07:48:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:48:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:48:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:48:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:48:26 INFO - Running setup.py install for mozleak 07:48:27 INFO - Running setup.py install for mozlog 07:48:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:48:27 INFO - Running setup.py install for moznetwork 07:48:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:48:27 INFO - Running setup.py install for mozprocess 07:48:27 INFO - Running setup.py install for mozprofile 07:48:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:48:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:48:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:48:27 INFO - Running setup.py install for mozrunner 07:48:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:48:27 INFO - Running setup.py install for mozscreenshot 07:48:28 INFO - Running setup.py install for moztest 07:48:28 INFO - Running setup.py install for mozversion 07:48:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:48:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:48:28 INFO - Cleaning up... 07:48:28 INFO - Return code: 0 07:48:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:48:28 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')]} 07:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:28 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')]} 07:48:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:28 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 0x10cdfac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa65b47ecd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb50d30>, '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 0x10cb1acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb41be8>, '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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:48:28 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 07:48:28 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 07:48:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r0B0gBnr19/Render', 07:48:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:28 INFO - 'HOME': '/Users/cltbld', 07:48:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:28 INFO - 'LOGNAME': 'cltbld', 07:48:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:28 INFO - 'MOZ_NO_REMOTE': '1', 07:48:28 INFO - 'NO_EM_RESTART': '1', 07:48:28 INFO - 'PAGER': '/bin/cat', 07:48:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:28 INFO - 'PWD': '/builds/slave/test', 07:48:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:28 INFO - 'SHELL': '/bin/bash', 07:48:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YZ5cAK0mwj/Listeners', 07:48:28 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:48:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:28 INFO - 'USER': 'cltbld', 07:48:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:28 INFO - 'XPC_FLAGS': '0x0', 07:48:28 INFO - 'XPC_SERVICE_NAME': '0', 07:48:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:48:28 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-IeZjv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:48:28 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)) 07:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:48:28 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-5CM9iQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:48:28 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)) 07:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:48:28 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-shFf7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:48:28 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)) 07:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:48:28 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-hepq_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:48:29 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)) 07:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:48:29 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-H6POe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:48:29 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)) 07:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:48:29 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-a6EmxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:48:29 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)) 07:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:48:29 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-iAjKjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:48:29 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)) 07:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:48:29 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-P_XHc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:48:29 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)) 07:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:48:29 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-ebgXn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:48:29 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)) 07:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:48:29 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-_d5b8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:48:29 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)) 07:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:48:29 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-wn9XtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:48:29 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)) 07:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:48:29 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-zEucpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:48:29 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)) 07:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:48:29 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-I9LJGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:48:30 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)) 07:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:48:30 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-A2m8Jm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:48:30 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)) 07:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:48:30 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-LB_twQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:48:30 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)) 07:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:48:30 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-OdGV_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:48:30 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)) 07:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:48:30 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-zenQRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:48:30 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)) 07:48:30 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)) 07:48:30 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)) 07:48:30 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)) 07:48:30 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)) 07:48:30 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)) 07:48:30 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)) 07:48:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:48:30 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 07:48:30 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 07:48:30 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 07:48:30 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 07:48:31 INFO - Downloading blessings-1.6.tar.gz 07:48:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:48:32 INFO - Installing collected packages: blessings 07:48:32 INFO - Running setup.py install for blessings 07:48:32 INFO - Successfully installed blessings 07:48:32 INFO - Cleaning up... 07:48:32 INFO - Return code: 0 07:48:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:48:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:48:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:48:32 INFO - Reading from file tmpfile_stdout 07:48:32 INFO - Current package versions: 07:48:32 INFO - blessings == 1.6 07:48:32 INFO - blobuploader == 1.2.4 07:48:32 INFO - docopt == 0.6.1 07:48:32 INFO - functools32 == 3.2.3-2 07:48:32 INFO - jsonschema == 2.5.1 07:48:32 INFO - manifestparser == 1.1 07:48:32 INFO - mozInstall == 1.13 07:48:32 INFO - mozcrash == 1.0 07:48:32 INFO - mozdebug == 0.1 07:48:32 INFO - mozdevice == 0.51 07:48:32 INFO - mozfile == 1.2 07:48:32 INFO - mozhttpd == 0.7 07:48:32 INFO - mozinfo == 0.10 07:48:32 INFO - mozleak == 0.1 07:48:32 INFO - mozlog == 3.4 07:48:32 INFO - moznetwork == 0.27 07:48:32 INFO - mozprocess == 0.25 07:48:32 INFO - mozprofile == 0.28 07:48:32 INFO - mozrunner == 6.13 07:48:32 INFO - mozscreenshot == 0.1 07:48:32 INFO - mozsystemmonitor == 0.3 07:48:32 INFO - moztest == 0.8 07:48:32 INFO - mozversion == 1.4 07:48:32 INFO - psutil == 3.1.1 07:48:32 INFO - requests == 1.2.3 07:48:32 INFO - wsgiref == 0.1.2 07:48:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:48:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:32 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 0x10cdfac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa65b47ecd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb50d30>, '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 0x10cb1acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb41be8>, '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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:48:32 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 07:48:32 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 07:48:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r0B0gBnr19/Render', 07:48:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:32 INFO - 'HOME': '/Users/cltbld', 07:48:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:32 INFO - 'LOGNAME': 'cltbld', 07:48:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:32 INFO - 'MOZ_NO_REMOTE': '1', 07:48:32 INFO - 'NO_EM_RESTART': '1', 07:48:32 INFO - 'PAGER': '/bin/cat', 07:48:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:32 INFO - 'PWD': '/builds/slave/test', 07:48:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:32 INFO - 'SHELL': '/bin/bash', 07:48:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YZ5cAK0mwj/Listeners', 07:48:32 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:48:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:32 INFO - 'USER': 'cltbld', 07:48:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:32 INFO - 'XPC_FLAGS': '0x0', 07:48:32 INFO - 'XPC_SERVICE_NAME': '0', 07:48:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:32 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)) 07:48:32 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)) 07:48:32 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)) 07:48:32 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)) 07:48:32 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)) 07:48:32 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)) 07:48:32 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)) 07:48:32 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)) 07:48:32 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)) 07:48:32 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:48:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:48:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:48:34 INFO - Downloading marionette_driver-1.4.0.tar.gz 07:48:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 07:48:34 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)) 07:48:34 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)) 07:48:34 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)) 07:48:34 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)) 07:48:34 INFO - Installing collected packages: marionette-driver 07:48:34 INFO - Running setup.py install for marionette-driver 07:48:34 INFO - Successfully installed marionette-driver 07:48:34 INFO - Cleaning up... 07:48:34 INFO - Return code: 0 07:48:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfe768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa65b47ecd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb50d30>, '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 0x10cb1acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb41be8>, '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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:48:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:48:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r0B0gBnr19/Render', 07:48:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:34 INFO - 'HOME': '/Users/cltbld', 07:48:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:34 INFO - 'LOGNAME': 'cltbld', 07:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:34 INFO - 'MOZ_NO_REMOTE': '1', 07:48:34 INFO - 'NO_EM_RESTART': '1', 07:48:34 INFO - 'PAGER': '/bin/cat', 07:48:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:34 INFO - 'PWD': '/builds/slave/test', 07:48:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:34 INFO - 'SHELL': '/bin/bash', 07:48:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YZ5cAK0mwj/Listeners', 07:48:34 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:48:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:34 INFO - 'USER': 'cltbld', 07:48:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:34 INFO - 'XPC_FLAGS': '0x0', 07:48:34 INFO - 'XPC_SERVICE_NAME': '0', 07:48:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:48:34 INFO - Cleaning up... 07:48:34 INFO - Return code: 0 07:48:34 INFO - Running post-action listener: _resource_record_post_action 07:48:34 INFO - Running post-action listener: _start_resource_monitoring 07:48:34 INFO - Starting resource monitoring. 07:48:34 INFO - [mozharness: 2017-06-13 14:48:34.809116Z] Finished create-virtualenv step (success) 07:48:34 INFO - [mozharness: 2017-06-13 14:48:34.810109Z] Running install step. 07:48:34 INFO - Running pre-action listener: _resource_record_pre_action 07:48:34 INFO - Running main action method: install 07:48:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:48:35 INFO - Reading from file tmpfile_stdout 07:48:35 INFO - Detecting whether we're running mozinstall >=1.0... 07:48:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:48:35 INFO - Reading from file tmpfile_stdout 07:48:35 INFO - Output received: 07:48:35 INFO - Usage: mozinstall [options] installer 07:48:35 INFO - Options: 07:48:35 INFO - -h, --help show this help message and exit 07:48:35 INFO - -d DEST, --destination=DEST 07:48:35 INFO - Directory to install application into. [default: 07:48:35 INFO - "/builds/slave/test"] 07:48:35 INFO - --app=APP Application being installed. [default: firefox] 07:48:35 INFO - mkdir: /builds/slave/test/build/application 07:48:35 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'] 07:48:35 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 07:48:56 INFO - Reading from file tmpfile_stdout 07:48:56 INFO - Output received: 07:48:56 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:48:56 INFO - Running post-action listener: _resource_record_post_action 07:48:56 INFO - [mozharness: 2017-06-13 14:48:56.567574Z] Finished install step (success) 07:48:56 INFO - [mozharness: 2017-06-13 14:48:56.567714Z] Running setup-mitmproxy step. 07:48:56 INFO - Running pre-action listener: _resource_record_pre_action 07:48:56 INFO - Running main action method: setup_mitmproxy 07:48:56 INFO - Skipping: mitmproxy is not required 07:48:56 INFO - Running post-action listener: _resource_record_post_action 07:48:56 INFO - [mozharness: 2017-06-13 14:48:56.568292Z] Finished setup-mitmproxy step (success) 07:48:56 INFO - [mozharness: 2017-06-13 14:48:56.568403Z] Running run-tests step. 07:48:56 INFO - Running pre-action listener: _resource_record_pre_action 07:48:56 INFO - Running main action method: run_tests 07:48:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:48:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:48:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:48:56 INFO - 2017-06-13 07:48:56.583 screenresolution[6626:118300] starting screenresolution argv=screenresolution get 07:48:56 INFO - 2017-06-13 07:48:56.586 screenresolution[6626:118300] Display 0: 1600x1200x32@60 07:48:56 INFO - 2017-06-13 07:48:56.592 screenresolution[6627:118302] starting screenresolution argv=screenresolution list 07:48:56 INFO - Available Modes on Display 0 07:48:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:48:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:48:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:48:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:48:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:48:56 INFO - Intel Iris: 07:48:56 INFO - Chipset Model: Intel Iris 07:48:56 INFO - Type: GPU 07:48:56 INFO - Bus: Built-In 07:48:56 INFO - VRAM (Dynamic, Max): 1536 MB 07:48:56 INFO - Vendor: Intel (0x8086) 07:48:56 INFO - Device ID: 0x0a2e 07:48:56 INFO - Revision ID: 0x0009 07:48:56 INFO - Displays: 07:48:56 INFO - Display: 07:48:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:48:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:48:56 INFO - Display Serial Number: 1600x1200 60 07:48:56 INFO - Main Display: Yes 07:48:56 INFO - Mirror: Off 07:48:56 INFO - Online: Yes 07:48:56 INFO - Rotation: Supported 07:48:56 INFO - Return code: 0 07:48:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:48:56 INFO - Python 2.7.3 07:48:56 INFO - Return code: 0 07:48:56 INFO - grabbing minidump binary from tooltool 07:48:56 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')]} 07:48:56 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 0x10cb50d30>, '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 0x10cb1acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb41be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:48:56 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 07:48:56 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 07:48:56 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 07:48:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:48:56 INFO - Return code: 0 07:48:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:48:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:48:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:48:56 INFO - ENV: RUST_BACKTRACE is now 1 07:48:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:48:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:48:56 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/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--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 07:48:56 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/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0383 --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 07:48:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r0B0gBnr19/Render', 07:48:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:56 INFO - 'HOME': '/Users/cltbld', 07:48:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:56 INFO - 'LOGNAME': 'cltbld', 07:48:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:48:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:56 INFO - 'MOZ_NO_REMOTE': '1', 07:48:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:48:56 INFO - 'NO_EM_RESTART': '1', 07:48:56 INFO - 'PAGER': '/bin/cat', 07:48:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:56 INFO - 'PWD': '/builds/slave/test', 07:48:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:48:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:56 INFO - 'RUST_BACKTRACE': '1', 07:48:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:48:56 INFO - 'SHELL': '/bin/bash', 07:48:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YZ5cAK0mwj/Listeners', 07:48:56 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:48:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:56 INFO - 'USER': 'cltbld', 07:48:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:56 INFO - 'XPC_FLAGS': '0x0', 07:48:56 INFO - 'XPC_SERVICE_NAME': '0', 07:48:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:56 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/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--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 07:48:57 INFO - mozversion application_buildid: 20170613064334 07:48:57 INFO - mozversion application_changeset: e8dd9736241ea1989feaf3c85a71593c8a6f3bbb 07:48:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:48:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:48:57 INFO - mozversion application_name: Firefox 07:48:57 INFO - mozversion application_remotingname: firefox-dev 07:48:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:48:57 INFO - mozversion application_vendor: Mozilla 07:48:57 INFO - mozversion application_version: 55.0 07:48:57 INFO - mozversion platform_buildid: 20170613064334 07:48:57 INFO - mozversion platform_changeset: e8dd9736241ea1989feaf3c85a71593c8a6f3bbb 07:48:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:48:57 INFO - mozversion platform_version: 55.0 07:48:57 INFO - using testdate: 1497365337 07:48:57 INFO - actual date: 1497365337 07:48:57 INFO - starting webserver on 'localhost:56895' 07:48:57 INFO - SUITE-START | Running 2 tests 07:48:57 INFO - TEST-START | dromaeo_css 07:48:57 INFO - operating with platform_type : mac_ 07:48:57 INFO - Initialising browser for dromaeo_css test... 07:48:57 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:56895/getInfo.html -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmp3Eg_I4/profile 07:48:57 INFO - TEST-INFO | started process 6637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:56895/getInfo.html -foreground) 07:49:31 INFO - TEST-INFO | 6637: killed by SIGTERM 07:49:31 INFO - Browser initialized. 07:49:31 INFO - Running cycle 1/1 for dromaeo_css test... 07:49:31 INFO - TEST-INFO | started process 6645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmp3Eg_I4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:49:32 INFO - PID 6645 | 2017-06-13 07:49:32.817 plugin-container[6646:118784] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994b, name = 'com.apple.tsm.portname' 07:49:32 INFO - PID 6645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:49:32 INFO - PID 6645 | 2017-06-13 07:49:32.817 plugin-container[6646:118784] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 07:49:32 INFO - PID 6645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:49:32 INFO - PID 6645 | 2017-06-13 07:49:32.817 plugin-container[6646:118784] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:49:33 INFO - PID 6645 | 2017-06-13 07:49:33.574 plugin-container[6647:118827] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 07:49:33 INFO - PID 6645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:49:33 INFO - PID 6645 | 2017-06-13 07:49:33.574 plugin-container[6647:118827] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 07:49:33 INFO - PID 6645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:49:33 INFO - PID 6645 | 2017-06-13 07:49:33.574 plugin-container[6647:118827] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:49:42 INFO - PID 6645 | RSS: Main: 214802432 07:49:42 INFO - PID 6645 | 07:52:45 INFO - PID 6645 | Cycle 1(1): loaded http://localhost:56895/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56895/tests/dromaeo/cssquery-ext.html) 07:52:47 INFO - PID 6645 | RSS: Main: 232869888 07:52:47 INFO - PID 6645 | 07:55:49 INFO - PID 6645 | Cycle 1(1): loaded http://localhost:56895/tests/dromaeo/cssquery-ext.html (next: http://localhost:56895/tests/dromaeo/cssquery-jquery.html) 07:55:50 INFO - PID 6645 | RSS: Main: 239624192 07:55:50 INFO - PID 6645 | 07:58:52 INFO - PID 6645 | Cycle 1(1): loaded http://localhost:56895/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56895/tests/dromaeo/cssquery-mootools.html) 07:58:52 INFO - PID 6645 | RSS: Main: 242872320 07:58:52 INFO - PID 6645 | 08:01:54 INFO - PID 6645 | Cycle 1(1): loaded http://localhost:56895/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56895/tests/dromaeo/cssquery-prototype.html) 08:01:55 INFO - PID 6645 | RSS: Main: 245510144 08:01:55 INFO - PID 6645 | 08:04:57 INFO - PID 6645 | Cycle 1(1): loaded http://localhost:56895/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56895/tests/dromaeo/cssquery-yui.html) 08:04:58 INFO - PID 6645 | RSS: Main: 246038528 08:04:58 INFO - PID 6645 | 08:08:00 INFO - PID 6645 | Cycle 1(1): loaded http://localhost:56895/tests/dromaeo/cssquery-yui.html (next: http://localhost:56895/tests/dromaeo/cssquery-dojo.html) 08:08:00 INFO - PID 6645 | RSS: Main: 244187136 08:08:00 INFO - PID 6645 | 08:08:00 INFO - PID 6645 | __start_tp_report 08:08:00 INFO - PID 6645 | _x_x_mozilla_page_load 08:08:00 INFO - PID 6645 | _x_x_mozilla_page_load_details 08:08:00 INFO - PID 6645 | |i|pagename|runs| 08:08:00 INFO - PID 6645 | |0;dojo.html;3418.572678415198;3452.6719961603653;3498.6851183393496;3560.7151427885765;3579.123114836864;6108.190302030429;6148.754049838007;6176.474999625032;6189;6220.533459990496;6182.659953702545;6194.163787888634;6204;6206.224221972254;6210.503159747221;5386.138217885129;5388.191771234307;5441.945580544183;5468.398476167618;5532.419095994903;4525.977370113144;5296.258523806651;5440.972795136038;5445.945540544583;5483.890322193553;5334.066538355792;5361.2226227197225;5384.300040994665;5385.515303622673;5407.837764867021;5456.372517160537;5485.341758988907;5515.172724091378;5519.530839878616;5526.613137080406;5377.7311134443125;5396.4333744957;5447.945520544784;5479.068558345091;5482.506574408282;6126.938730612681;6205.100260462208;6223.941929871932;6224.06639004153;6225.470834979033;6424.164858568403;6700.530962832604;6722.193336799566;6729.966350168267;6745.460363170934;6523.7064332105165;6550.639714815683;6659.500537459661;6741.966290168518;6788.151481064867;6255.343188965138;6264.592801467936;6293.842653933688;6320.146780184617;6349.206349206349;6708.262091169968;6914.619695916773;6931.133608298963;6940.548864323803;6941.409980151744;6427.903581446282;6437.388448097423;6460.644664543402;6473.385028422293;6499.967500162469;6764.188297404245;6781.525293229525;6789;6814.114165158597;6836.589804611739;6721.66391680414;6725.495587830932;6727.562708423937;6731.663416829237;6738;6449.967750161313;6540.084388185658;6541.8691626168165;6733.3939945405145;6737.8652426951185;5383.084875571162;5397.298351214317;5398.541124004503;5431.53831924283;5448.155535891943;4738.976305118453;4816.759162041884;4866.245731911559;4867.610591152699;4886.731229782325;6222.533310001768;6226.626402415779;6276.874462510816;6321.462675672619;6347.809565713036;6816.965915170393;6834.829129271709;6846.110005699227;6849.965750171317;6867.2102708188195;6187.412195841388;6276.058591211355;6305.621662700252;6319.55763096588;6332.8416789579705;5835.591508594358;5899.675517846474;5932.8813423732145;5934.406559344117;5942.61373010753;6799.456043516507;6837.35045170718;6885.173779146434;6938.410235130071;6953.617551034539;6592.110065141336;6782.728690852311;6790.151231096113;6805.931940680728;6810.523263371715;6915.066466027022;6931.09895713554;6956.40870526011;6961;6964.477664175106;6673.766418175534;6689.732410703517;6698.5980841149685;6703;6703.29615390377;6381.361863813581;6556.934430655632;6824.965875170592;6831.59010459374;6879.931200687929;5657.943420565906;6401.455876250571;6405.51958603116;6451.935480645134;6470.935290647033;6095.451409373178;6493.837654058686;6574.704138313883;6584.473242140713;6587.176602924635;6153.107799368977;6285.59143655661;6302.590331628429;6456.192975878015;6484.967575162094;6396.776112836214;6454.225492940877;6486.902696459462;6509.056186852785;6516.902246466212;6458.515611329263;6469.644169570719;6491.902621460587;6496.70764815575;6501.317361676955 08:08:00 INFO - PID 6645 | |1;ext.html;836.4563034027881;852.5524099847581;1242.7079636285478;1285.4665313894734;1288.4073326269922;36083.4587481188;39813.203735925264;39885;39905.802825915234;40045.998850028765;34101;40781.59218407815;40785.59214407855;40856.18287634246;40980.79509602455;7784.571848548328;7905.960470197641;7924.603769811486;7930.643121059537;7940.483868548555;6770.72917083316;7931.087924888625;8292.295154911839;8294.253517183452;8300.418970672055;8321.043080045782;8366.58167091643;8378.162183781604;8399.076101628847;8410.705625303115;8301.418900676956;8358.83282334356;8367.497950123012;8376.371772117116;8381.580920953958;8126.553039582821;8158.428909976304;8191.262786349257;8254.711085112052;8278.751637450887;34686;37461.62538374608;37638.43542346867;37665.246695065944;37765.244695106216;3129.7339726123305;3566.1797786509246;3573.821308934569;3586.085548185196;3587.121155316938;27178.456430871272;29398.559021614692;30815.383692326028;30824.229394265098;30872.53691194634;3049.832259225742;3147.1817327494787;3165.5884734984534;3176.65056843747;3205.743540516753;288854.55572722;300294;315138;317290.4135479331;318438;38365;40321.39517907234;49264;49715.75142124266;49731;41377.586224137376;41507.58492415098;41534.79232603818;50697;51542.742286288325;15648.608784780472;15732.370705171665;15865.04809711421;15906.44327448557;15918.283677234553;12670.936645316715;12798.552050678181;12820.846123848898;12864.292463914575;12868.420921058574;13874.375653095634;13901.304934753221;13913;13925.094868833494;14031.649208769659;6112.724927378279;6142.938570614326;6144.969275153596;6147.692615369214;6157.8460538486;896.3322324868016;954.4464210757883;975.6926568131016;989.7921436498272;1054.0566193257146;22898.771012289664;23043;23467.882660586587;23684.7631523686;24264.51470970606;1014.7108074198818;1082.91740139673;1127.6560649002133;1131.479519421059;1132.7168207948014;12069.57756478519;13792.93103534496;15694.921525392529;15901.363945442054;15970.520884373487;332050;332887;333605.33197333856;335008;336316;56295.59261018507;56331.71834140803;56511.15233271504;56614.7169264151;56638.43361566414;1455.4105587237186;1527.4348491058158;1553.6038310231045;1555.2146166186003;1560.6254498920405;22948.88525557428;27293.181204563894;28912;29149.708502915546;29474.705252947195;39533;39659.60340396559;39767.60232397639;39773.40339894962;39774;255974;258344;287054;288008;288996;29797.10608681743;30377;30384.392312154076;35858.28283434369;35992.460113098845;37346.813265933495;37410.251794963406;38424.6157538421;42105.36841947435;42835;28569.714302857537;28703.569446457903;29838.10485685433;30657.386852262385;31236.219094522818;29451.263718407212;29973.250668733457;30302.393952121274;30663.08010759681;30832.53751193689;36040.819795900854;36136;36574.268514630094;37165.07087322839;37221.813890930374;29139.417211656073;30079;31044.91342802991;32101.59238057202;32504.512432313062;18745.6250874979;23304.883475582512;23528.29415117549;25123.497530049663;25202.117925872506 08:08:00 INFO - PID 6645 | |2;jquery.html;497.96174975176757;506.64281681414604;514.6886133888997;527.765144510693;533.5624787674107;21891;22552.88723556382;22709.432264193405;23025.654615180745;23169.420764480874;21614.027368768402;23806.5238695226;24116.276511704633;24940.004799616032;24942.625860612105;6504.577202481835;6612.70242839071;6645.3354664533645;6660.9000864987065;6662.666866656672;6114.480269177126;6362.872742545146;6390.840228994266;6431.807045788634;6577.112089867855;6386.744530218785;6395.8401039973905;6403.871922561546;6404.615723056631;6409.038644203383;6347.873042539147;6401.391867772554;6404.583702059398;6424.293327733945;6428.967855160718;5792.420757924216;5798.275215598051;5827.592068555203;5867.735951882176;5873.20711703923;24367.634485482733;28166.859165704245;28571.85714071437;29529.114126576238;30119.849400753075;2227.855189412683;2295.173737454515;2295.5638428698494;2298.310506847939;2299.3906614747107;18513.35203267879;19049.523761905926;19099.854008759517;19114.904425477922;19124.904375478032;2144.6246906791407;2180.607490651682;2203.3610253026604;2208.3595757230382;2220.367195349334;145119;154964;155062;155520;177969;13790.448382064806;14526.346314415876;14536.563903082924;14892.140564233849;15104.320305586274;20039;20049.997500124937;20175.39473815788;20211.292604758764;20263.898680506503;7445.8883116753295;9270.609408588765;9688.079632434907;9903.950480247553;10126.949365253275;8322.750317490485;8788.209061184523;9010.64840273951;9466.810663786824;9484.952575237217;8801.603927823264;8899.466032038099;8965;9003.549822508849;9019.503927284062;2152.9127790465714;2536.961945570818;2550.8214424990074;2572.691277046729;2683.7316268373397;929.0894922975522;930.8277968575835;930.9813803723888;967.8983706710694;992.712113487095;20390.898045509974;20614.484637883874;20650.896745516176;21626.024385368968;21759.564808703737;986.452518852044;1006.3207335048813;1013.2470825471975;1030.8917563655857;1042.4162469017374;10447.373157610567;10564.524596393027;10569.841452378221;10683.771366292976;11321.094312454983;499514;521875;531252;534348;535213;107478;142003.86994195095;146426.26786865998;146749;147565.2621736906;980.2403137568441;1025;1029.6499190275342;1148.8851114888612;1153.6308381317897;18616.2553497864;19381.4185574433;19408.029598519734;20853.957302134528;21860.89069554642;55815;60287.69856150867;61386.77226455357;62552;64174;77053;78488.21511784809;79764.80352794829;80728.59635701784;81192.7821082696;11596.304221746723;11629.476673549541;11683.18217724751;11749.76500470003;11795.705107372385;15771.818386830177;17941.820581794014;18334.449966501026;18603.255869765057;18604.97672627964;14033.368498417392;14905.105693658415;15095.849041509884;15098.39606415731;15155.469558565397;12802.807957880825;13030.609081727564;13108.147970381895;13234.941204703407;13649.249291289074;14959.626009349853;15039.172845493604;15270.23648817577;15285.235738213267;15318.46385376551;11341.09271258281;11495.885041149482;11496.712582185512;11498.94250528742;11500.022498087756;14059.297035148405;14867.256637167882;15236.619084522976;15262.08427494354;15334.846651533342 08:08:00 INFO - PID 6645 | |3;mootools.html;1410.132768347466;1455.3148794770805;1469.7433694191466;1480.0527662296126;1484.5991582272786;13462.798058029126;14110.506132285373;14114.505992290271;14118.364673589687;14160.29198540074;13288.534901278457;14075.92962035191;14149.36327865246;14152.150870947724;14183.149011059344;2664.906728264516;2734.4531093781225;2736.2201772494745;2739.1782465260444;2743.1222008957143;2710.0650275654966;2801.145650576573;2813.606095146681;2821.4639218548514;2830.193394882459;2780.416112616353;2784.1647505748297;2805.424887897976;2808.7612552932924;2817.760490358322;2737.096758069832;2752.862356882158;2782.1514438096374;2791.4556661451024;2799.0623141247706;2758.089830355978;2772.209920172761;2791.637087178674;2795.412963277694;2809.311718628949;13538.255395953218;13650.658733531642;13678.863211367858;13699.520516781864;13705;6584.7366105356205;7580;7809.258120478546;7833.412494062968;7839.059312882434;4596.47140578831;4829.323894654751;4892.339534162914;4922.507749225048;4932.778024988884;1404.353997161307;1404.9157050576998;1409.415092736513;1423.9715205695932;1425.0024982512282;20789.792102078936;20800;20901.790982090137;20907.05918233683;20938.476538086517;6623.6688165592495;6703.530752847254;6870.931290687029;6881.277465866012;6900.171979362408;11557.479913403917;11796.646100616996;14177.291135443187;14278.785818212735;14287.928560357132;7377.26227377258;7470.4770666052855;7526.623668816646;7536.434767392468;7537;6103.38966103395;6888.070110535014;7089.822754431078;7097.432205423554;7133.429325653935;5000.349954505964;5190.065788158092;5201.739913004335;5244.685318880803;5303.840884773463;1275.9553415630407;1526.5496678479876;1546.381447421018;1549.457689808558;1566.772817941392;819.2503858969096;842.7977285451448;853.3514528957912;856.3448961544424;872.7512658892185;7057.435405167575;7188.568685878864;7372.741954031583;7443.590602516805;7447.88828167578;685.3283781893772;689.8930665746817;707.2361849202848;710.6695386645234;712.6508011074536;4575.153596584642;4919.1883339248625;4972.925406118911;5018.949810501921;5261.394939581997;18234.45296641103;18464.538386540175;18792.624147516974;18959.715604265948;18999;18331.083445827655;18494.630107397777;18529.629407412045;18999.240030398905;19039.714404283946;9770.120689138012;9782.364146330467;9864.309498335191;9867.605295788231;9935.205183585296;6967.268436814062;7585;7647.464677472746;7810.882836757567;8029.518228906284;16715.247813848164;16995.49013529596;17198.31206751716;17204.13979301055;17239.22423490971;16959.067251301298;17203.22585483681;17394.73907891357;17876.195571199587;17879.821201787814;9837.262205334771;9979.151772099453;9983.500824958577;10743.946280268548;11242.931921467447;12778.936105319413;12791.616251512469;12862;12914.031447641652;12932.288724120264;9931.751706207402;10059;10252.38485690861;10291;10590;8359.874601881098;9252.074792520694;9892.208623310262;10032.097111260022;10186.286959912735;9305.348625596143;9383.390079644802;9408.858867116862;9444.150026497648;9449.622015119317;6504.85920757538;6710.228323742636;6839.965800170968;7080.672084120455;7153.785386438415;6893.724251029903;7014.298570142945;7175.569465832067;7293.234210408043;7297.817554561178 08:08:00 INFO - PID 6645 | |4;prototype.html;387.65886020302133;427.5105004769537;432.20474327237895;468.5431704088512;472.2632692998922;6431.588154442078;6509.609423434597;6538.248101468333;6551.443127334176;6561.1470508833845;6119.510439164867;6315.778947736831;6324.652144132071;6345.873082538346;6351.9682401587925;3964.425158352037;4760.976195119037;4789.449213340458;4799.472058073609;4809.807607695688;4758.287016673995;4800.183968725325;4804.423469183703;4812.23004319309;4834.758262086899;4552.817887284505;4745.59662428694;4768.523147685221;4789.156174872682;4797;4716.575508204278;4730.195866702668;4737.820602522844;4747.335373047776;4755.262934245197;3634.164746802874;4172.958270417287;4185.539590645027;4188.350805625133;4189.916201675949;4438.201123797715;4758.479444527712;5571.913476872011;6157.507399408037;6251.281102673205;5403.810866619689;5612.354579223401;5615.6630602164005;5632.690202038886;5634.690092044936;6027.547933904974;6701.08502801283;6814.557053791537;6819.454443644546;6835.384815366591;3979.960200397988;4759.524047595248;4762.476127625959;4769.737664428455;4794.065157294329;5750.6262092963825;6983.755568555127;7132.6077065761365;7267.345938865475;7285.380742636882;4865.70805751656;4874.341963834908;4876.536729010738;4903.362562866804;4905.950940490549;4972;4976.477469865685;4983.626228032912;4989.90020199594;5009.774560144802;4778.402699662543;5667.716614169275;5672;5697.774978379668;5740.483356497935;5992.071228959431;6861.416779573692;6879.208890977501;6900.516963812485;7109.928900710926;1579.5893067802224;1640.8277130901322;1675.7653928450027;1703.6677847819556;1703.7444383342597;915.5651065743851;1058.2539309786582;1067.6636859389423;1116.1405717597404;1162.5756598841363;6261.467775239155;6397.936020639827;6405.743770249138;6526.172671651945;6580.76967306142;7071.61106139157;7087.547052854153;7113.53762005468;7114.608696521637;7143.67841949241;6190.907136392958;6479.546431749733;6501.837454063686;6506.3818937200895;6513.609183448913;3140.8586613602442;3402.5066365376883;3495.213576945167;3657.6708096271464;3676.9080772980888;7221.8555628887125;7231.566106033655;7313.268673132644;7337.339639432476;7344.008558844487;5766.538676905838;7173.210946795848;7239.9276007240305;7286.635668216568;7339.88990165148;6388.6903184847;6551.934480655133;6877.140357455318;7261.237570055068;7317.926820731725;5655.943440565542;7056.294370562902;7065.682044307916;7115.82210444743;7145.535540189871;5835.803660249554;6946.270641582763;6946.930530694628;6957.599628128288;6976;7016.614086225102;7257.782266532013;7342.632868356668;7358.558486490828;7374.705011799468;3925.5878132795647;4746.02706445171;4754.857354279377;4846.321514987975;4861.902761944671;6850.4862135341045;7126.144862616519;7150.641378138345;7191.82020449472;7215.314545118205;6811.8978215325815;6833.3166683331265;6854.725810967506;6862.862742745217;6887.7244910203035;6877.38103570661;6878.65606719672;6881.41507971828;6882.105326307548;6916.204636466669;5746.655200687972;6685.064091027358;6766.898496522655;6773.559718618274;6776.864462710817;5735.569832262514;5956.344802071682;6060.939335616337;6823.181218253642;6881.346272104142;6647.667616619053;6662.201205674538;6723.428508576826;6752.662366881538;6764.763233286812 08:08:00 INFO - PID 6645 | |5;yui.html;821.6384790692091;840.3697227079691;840.3991146330376;851.0680804519052;861.655337864854;16974;16999.745003824937;17186.312547498084;17205.655886882254;17225.397111101112;16826.24281907314;17910.462686119405;18076.09619519022;18300.176492057857;18318.175682094305;928.7724507495673;936.372630337672;937.8921424036221;946.4368700623119;952.333366643349;809.6437567470324;947.7535840681438;961.3799099580766;964.4117088575963;968.602872822143;946.857971304303;979.7844474215661;989.1098011789411;998.7852611688486;1014.0569270578359;905.6286922361833;983.0709979069782;994.2195376629384;996.2229461020415;996.566493575297;634.3212762344293;722.0793488302404;731.7950973727364;734.4711807498593;737.5906371963558;14577.396486386595;16884.746728799077;16953.237104330336;17033.233504492327;17133.657326853394;17213.483595492155;17601.55996100095;17694.911525442418;17805.931644101267;17951.640967180712;14505.057171283834;15081.622959425993;15125.941184117117;15126;15163.924180379137;3660.798656073915;3693.593704692482;3706.7405281630295;3708.758930669525;3718.9256214875822;147528;148038;148439;150713.9999999989;152512.2374388121;13630.931845340909;13669.248191349372;13861.306934653227;13867.237301948287;14142.50501232452;25832.74167258341;26664.73335266661;26803;26885.059022934103;26915.92336306526;8130;8244.464109832841;8302.501849888906;8317.916820831835;8346.457480263764;4081.9795901020307;4121.9587804122175;4135.400366946836;4179.059711564873;4187.078842654672;4601.792919318638;4655.162070827216;4733.857984260478;4755.952440475551;4791.520847915181;864.0452300208179;867.1631875240423;898.4878619187001;1032.2155162076876;1046.1108058150512;638.1321402892063;645.2450632759683;668.1514476614672;679.8408713144078;689.8137502874196;10578.206634502443;11173.050290725218;11745.647630571097;12031.06450336381;12123.75752484963;609.2141137932018;616.6978180691428;626.5614070150913;639.3191251317425;648.18652590998;4458.888527786831;4581.854536365909;4915.26271059344;5218.347706536683;5311.787528498817;134020;148712;156824.21587892214;158627;161348;126582;127619.89520419079;128869;129005;130797.69202308045;108619.99999999841;111237;111769.88230117595;112695;112849.30726039116;11078.11375090007;11091.889081109086;11133.276337038067;11555.306681599131;11760.840017326282;38111;45548;46266.53733462622;46665.3000205004;48176.79558010938;76845.23154768381;79649.8052529201;83908;87613.56193219248;88115;15548.844511555191;17637.647247055243;18080.547986300448;18190.636187276446;18496.44510664682;18276.634467310847;18955.43133705936;19077.80922190816;19211.90394048021;19591;15366.923165384549;15579.143147127017;15650.530484085495;15792.289346979183;18264.908675456536;16144.596385090466;17123.97256164634;17767.822321776617;18233.179506921955;18710.06449677538;17167.914160429118;17713.822861771216;17820.10899455048;17915.552111197325;18455.72316415228;11088.223824332543;11568.421578920852;11913.880861191277;12224.266544007389;12242.510299588273;11984.221025633306;12571.94491916651;13066.934665326613;13088.411021503865;13186.736265274832 08:08:00 INFO - PID 6645 | __end_tp_report 08:08:00 INFO - PID 6645 | __start_cc_report 08:08:00 INFO - PID 6645 | _x_x_mozilla_cycle_collect,404 08:08:00 INFO - PID 6645 | __end_cc_report 08:08:00 INFO - PID 6645 | __startTimestamp1497366480454__endTimestamp 08:08:00 INFO - PID 6645 | 08:08:00 INFO - PID 6645 | ------- Summary: start ------- 08:08:00 INFO - PID 6645 | Number of tests: 6 08:08:00 INFO - PID 6645 | 08:08:00 INFO - PID 6645 | [#0] dojo.html Cycles:165 Average:6173.51 Median:6401.46 stddev:735.45 (11.5%) stddev-sans-first:705.24 08:08:00 INFO - PID 6645 | Values: 3418.6 3452.7 3498.7 3560.7 3579.1 6108.2 6148.8 6176.5 6189.0 6220.5 6182.7 6194.2 6204.0 6206.2 6210.5 5386.1 5388.2 5441.9 5468.4 5532.4 4526.0 5296.3 5441.0 5445.9 5483.9 5334.1 5361.2 5384.3 5385.5 5407.8 5456.4 5485.3 5515.2 5519.5 5526.6 5377.7 5396.4 5447.9 5479.1 5482.5 6126.9 6205.1 6223.9 6224.1 6225.5 6424.2 6700.5 6722.2 6730.0 6745.5 6523.7 6550.6 6659.5 6742.0 6788.2 6255.3 6264.6 6293.8 6320.1 6349.2 6708.3 6914.6 6931.1 6940.5 6941.4 6427.9 6437.4 6460.6 6473.4 6500.0 6764.2 6781.5 6789.0 6814.1 6836.6 6721.7 6725.5 6727.6 6731.7 6738.0 6450.0 6540.1 6541.9 6733.4 6737.9 5383.1 5397.3 5398.5 5431.5 5448.2 4739.0 4816.8 4866.2 4867.6 4886.7 6222.5 6226.6 6276.9 6321.5 6347.8 6817.0 6834.8 6846.1 6850.0 6867.2 6187.4 6276.1 6305.6 6319.6 6332.8 5835.6 5899.7 5932.9 5934.4 5942.6 6799.5 6837.4 6885.2 6938.4 6953.6 6592.1 6782.7 6790.2 6805.9 6810.5 6915.1 6931.1 6956.4 6961.0 6964.5 6673.8 6689.7 6698.6 6703.0 6703.3 6381.4 6556.9 6825.0 6831.6 6879.9 5657.9 6401.5 6405.5 6451.9 6470.9 6095.5 6493.8 6574.7 6584.5 6587.2 6153.1 6285.6 6302.6 6456.2 6485.0 6396.8 6454.2 6486.9 6509.1 6516.9 6458.5 6469.6 6491.9 6496.7 6501.3 08:08:00 INFO - PID 6645 | 08:08:00 INFO - PID 6645 | [#1] ext.html Cycles:180 Average:45543.85 Median:334306.67 stddev:80540.80 (24.1%) stddev-sans-first:80696.78 08:08:00 INFO - PID 6645 | Values: 836.5 852.6 1242.7 1285.5 1288.4 36083.5 39813.2 39885.0 39905.8 40046.0 34101.0 40781.6 40785.6 40856.2 40980.8 7784.6 7906.0 7924.6 7930.6 7940.5 6770.7 7931.1 8292.3 8294.3 8300.4 8321.0 8366.6 8378.2 8399.1 8410.7 8301.4 8358.8 8367.5 8376.4 8381.6 8126.6 8158.4 8191.3 8254.7 8278.8 34686.0 37461.6 37638.4 37665.2 37765.2 3129.7 3566.2 3573.8 3586.1 3587.1 27178.5 29398.6 30815.4 30824.2 30872.5 3049.8 3147.2 3165.6 3176.7 3205.7 288854.6 300294.0 315138.0 317290.4 318438.0 38365.0 40321.4 49264.0 49715.8 49731.0 41377.6 41507.6 41534.8 50697.0 51542.7 15648.6 15732.4 15865.0 15906.4 15918.3 12670.9 12798.6 12820.8 12864.3 12868.4 13874.4 13901.3 13913.0 13925.1 14031.6 6112.7 6142.9 6145.0 6147.7 6157.8 896.3 954.4 975.7 989.8 1054.1 22898.8 23043.0 23467.9 23684.8 24264.5 1014.7 1082.9 1127.7 1131.5 1132.7 12069.6 13792.9 15694.9 15901.4 15970.5 332050.0 332887.0 333605.3 335008.0 336316.0 56295.6 56331.7 56511.2 56614.7 56638.4 1455.4 1527.4 1553.6 1555.2 1560.6 22948.9 27293.2 28912.0 29149.7 29474.7 39533.0 39659.6 39767.6 39773.4 39774.0 255974.0 258344.0 287054.0 288008.0 288996.0 29797.1 30377.0 30384.4 35858.3 35992.5 37346.8 37410.3 38424.6 42105.4 42835.0 28569.7 28703.6 29838.1 30657.4 31236.2 29451.3 29973.3 30302.4 30663.1 30832.5 36040.8 36136.0 36574.3 37165.1 37221.8 29139.4 30079.0 31044.9 32101.6 32504.5 18745.6 23304.9 23528.3 25123.5 25202.1 08:08:00 INFO - PID 6645 | 08:08:00 INFO - PID 6645 | [#2] jquery.html Cycles:180 Average:36366.68 Median:2214.36 stddev:89636.47 (4048.0%) stddev-sans-first:89847.47 08:08:00 INFO - PID 6645 | Values: 498.0 506.6 514.7 527.8 533.6 21891.0 22552.9 22709.4 23025.7 23169.4 21614.0 23806.5 24116.3 24940.0 24942.6 6504.6 6612.7 6645.3 6660.9 6662.7 6114.5 6362.9 6390.8 6431.8 6577.1 6386.7 6395.8 6403.9 6404.6 6409.0 6347.9 6401.4 6404.6 6424.3 6429.0 5792.4 5798.3 5827.6 5867.7 5873.2 24367.6 28166.9 28571.9 29529.1 30119.8 2227.9 2295.2 2295.6 2298.3 2299.4 18513.4 19049.5 19099.9 19114.9 19124.9 2144.6 2180.6 2203.4 2208.4 2220.4 145119.0 154964.0 155062.0 155520.0 177969.0 13790.4 14526.3 14536.6 14892.1 15104.3 20039.0 20050.0 20175.4 20211.3 20263.9 7445.9 9270.6 9688.1 9904.0 10126.9 8322.8 8788.2 9010.6 9466.8 9485.0 8801.6 8899.5 8965.0 9003.5 9019.5 2152.9 2537.0 2550.8 2572.7 2683.7 929.1 930.8 931.0 967.9 992.7 20390.9 20614.5 20650.9 21626.0 21759.6 986.5 1006.3 1013.2 1030.9 1042.4 10447.4 10564.5 10569.8 10683.8 11321.1 499514.0 521875.0 531252.0 534348.0 535213.0 107478.0 142003.9 146426.3 146749.0 147565.3 980.2 1025.0 1029.6 1148.9 1153.6 18616.3 19381.4 19408.0 20854.0 21860.9 55815.0 60287.7 61386.8 62552.0 64174.0 77053.0 78488.2 79764.8 80728.6 81192.8 11596.3 11629.5 11683.2 11749.8 11795.7 15771.8 17941.8 18334.4 18603.3 18605.0 14033.4 14905.1 15095.8 15098.4 15155.5 12802.8 13030.6 13108.1 13234.9 13649.2 14959.6 15039.2 15270.2 15285.2 15318.5 11341.1 11495.9 11496.7 11498.9 11500.0 14059.3 14867.3 15236.6 15262.1 15334.8 08:08:00 INFO - PID 6645 | 08:08:00 INFO - PID 6645 | [#3] mootools.html Cycles:180 Average:8393.79 Median:2803.29 stddev:5640.38 (201.2%) stddev-sans-first:5631.79 08:08:00 INFO - PID 6645 | Values: 1410.1 1455.3 1469.7 1480.1 1484.6 13462.8 14110.5 14114.5 14118.4 14160.3 13288.5 14075.9 14149.4 14152.2 14183.1 2664.9 2734.5 2736.2 2739.2 2743.1 2710.1 2801.1 2813.6 2821.5 2830.2 2780.4 2784.2 2805.4 2808.8 2817.8 2737.1 2752.9 2782.2 2791.5 2799.1 2758.1 2772.2 2791.6 2795.4 2809.3 13538.3 13650.7 13678.9 13699.5 13705.0 6584.7 7580.0 7809.3 7833.4 7839.1 4596.5 4829.3 4892.3 4922.5 4932.8 1404.4 1404.9 1409.4 1424.0 1425.0 20789.8 20800.0 20901.8 20907.1 20938.5 6623.7 6703.5 6870.9 6881.3 6900.2 11557.5 11796.6 14177.3 14278.8 14287.9 7377.3 7470.5 7526.6 7536.4 7537.0 6103.4 6888.1 7089.8 7097.4 7133.4 5000.3 5190.1 5201.7 5244.7 5303.8 1276.0 1526.5 1546.4 1549.5 1566.8 819.3 842.8 853.4 856.3 872.8 7057.4 7188.6 7372.7 7443.6 7447.9 685.3 689.9 707.2 710.7 712.7 4575.2 4919.2 4972.9 5018.9 5261.4 18234.5 18464.5 18792.6 18959.7 18999.0 18331.1 18494.6 18529.6 18999.2 19039.7 9770.1 9782.4 9864.3 9867.6 9935.2 6967.3 7585.0 7647.5 7810.9 8029.5 16715.2 16995.5 17198.3 17204.1 17239.2 16959.1 17203.2 17394.7 17876.2 17879.8 9837.3 9979.2 9983.5 10743.9 11242.9 12778.9 12791.6 12862.0 12914.0 12932.3 9931.8 10059.0 10252.4 10291.0 10590.0 8359.9 9252.1 9892.2 10032.1 10186.3 9305.3 9383.4 9408.9 9444.2 9449.6 6504.9 6710.2 6840.0 7080.7 7153.8 6893.7 7014.3 7175.6 7293.2 7297.8 08:08:00 INFO - PID 6645 | 08:08:00 INFO - PID 6645 | [#4] prototype.html Cycles:180 Average:5573.38 Median:6256.37 stddev:1734.46 (27.7%) stddev-sans-first:1695.09 08:08:00 INFO - PID 6645 | Values: 387.7 427.5 432.2 468.5 472.3 6431.6 6509.6 6538.2 6551.4 6561.1 6119.5 6315.8 6324.7 6345.9 6352.0 3964.4 4761.0 4789.4 4799.5 4809.8 4758.3 4800.2 4804.4 4812.2 4834.8 4552.8 4745.6 4768.5 4789.2 4797.0 4716.6 4730.2 4737.8 4747.3 4755.3 3634.2 4173.0 4185.5 4188.4 4189.9 4438.2 4758.5 5571.9 6157.5 6251.3 5403.8 5612.4 5615.7 5632.7 5634.7 6027.5 6701.1 6814.6 6819.5 6835.4 3980.0 4759.5 4762.5 4769.7 4794.1 5750.6 6983.8 7132.6 7267.3 7285.4 4865.7 4874.3 4876.5 4903.4 4906.0 4972.0 4976.5 4983.6 4989.9 5009.8 4778.4 5667.7 5672.0 5697.8 5740.5 5992.1 6861.4 6879.2 6900.5 7109.9 1579.6 1640.8 1675.8 1703.7 1703.7 915.6 1058.3 1067.7 1116.1 1162.6 6261.5 6397.9 6405.7 6526.2 6580.8 7071.6 7087.5 7113.5 7114.6 7143.7 6190.9 6479.5 6501.8 6506.4 6513.6 3140.9 3402.5 3495.2 3657.7 3676.9 7221.9 7231.6 7313.3 7337.3 7344.0 5766.5 7173.2 7239.9 7286.6 7339.9 6388.7 6551.9 6877.1 7261.2 7317.9 5655.9 7056.3 7065.7 7115.8 7145.5 5835.8 6946.3 6946.9 6957.6 6976.0 7016.6 7257.8 7342.6 7358.6 7374.7 3925.6 4746.0 4754.9 4846.3 4861.9 6850.5 7126.1 7150.6 7191.8 7215.3 6811.9 6833.3 6854.7 6862.9 6887.7 6877.4 6878.7 6881.4 6882.1 6916.2 5746.7 6685.1 6766.9 6773.6 6776.9 5735.6 5956.3 6060.9 6823.2 6881.3 6647.7 6662.2 6723.4 6752.7 6764.8 08:08:00 INFO - PID 6645 | 08:08:00 INFO - PID 6645 | [#5] yui.html Cycles:180 Average:26752.76 Median:18386.95 stddev:41709.56 (226.8%) stddev-sans-first:41781.13 08:08:00 INFO - PID 6645 | Values: 821.6 840.4 840.4 851.1 861.7 16974.0 16999.7 17186.3 17205.7 17225.4 16826.2 17910.5 18076.1 18300.2 18318.2 928.8 936.4 937.9 946.4 952.3 809.6 947.8 961.4 964.4 968.6 946.9 979.8 989.1 998.8 1014.1 905.6 983.1 994.2 996.2 996.6 634.3 722.1 731.8 734.5 737.6 14577.4 16884.7 16953.2 17033.2 17133.7 17213.5 17601.6 17694.9 17805.9 17951.6 14505.1 15081.6 15125.9 15126.0 15163.9 3660.8 3693.6 3706.7 3708.8 3718.9 147528.0 148038.0 148439.0 150714.0 152512.2 13630.9 13669.2 13861.3 13867.2 14142.5 25832.7 26664.7 26803.0 26885.1 26915.9 8130.0 8244.5 8302.5 8317.9 8346.5 4082.0 4122.0 4135.4 4179.1 4187.1 4601.8 4655.2 4733.9 4756.0 4791.5 864.0 867.2 898.5 1032.2 1046.1 638.1 645.2 668.2 679.8 689.8 10578.2 11173.1 11745.6 12031.1 12123.8 609.2 616.7 626.6 639.3 648.2 4458.9 4581.9 4915.3 5218.3 5311.8 134020.0 148712.0 156824.2 158627.0 161348.0 126582.0 127619.9 128869.0 129005.0 130797.7 108620.0 111237.0 111769.9 112695.0 112849.3 11078.1 11091.9 11133.3 11555.3 11760.8 38111.0 45548.0 46266.5 46665.3 48176.8 76845.2 79649.8 83908.0 87613.6 88115.0 15548.8 17637.6 18080.5 18190.6 18496.4 18276.6 18955.4 19077.8 19211.9 19591.0 15366.9 15579.1 15650.5 15792.3 18264.9 16144.6 17124.0 17767.8 18233.2 18710.1 17167.9 17713.8 17820.1 17915.6 18455.7 11088.2 11568.4 11913.9 12224.3 12242.5 11984.2 12571.9 13066.9 13088.4 13186.7 08:08:00 INFO - PID 6645 | -------- Summary: end -------- 08:08:00 INFO - PID 6645 | 08:08:01 INFO - TEST-INFO | 6645: exit 0 08:08:01 INFO - TEST-OK | dromaeo_css | took 1143800ms 08:08:01 INFO - TEST-START | kraken 08:08:01 INFO - operating with platform_type : mac_ 08:08:01 INFO - Initialising browser for kraken test... 08:08:01 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:56895/getInfo.html -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpPfYfQF/profile 08:08:01 INFO - TEST-INFO | started process 6653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:56895/getInfo.html -foreground) 08:08:34 INFO - TEST-INFO | 6653: killed by SIGTERM 08:08:34 INFO - Browser initialized. 08:08:34 INFO - Running cycle 1/1 for kraken test... 08:08:34 INFO - TEST-INFO | started process 6657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpPfYfQF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:08:35 INFO - PID 6657 | 2017-06-13 08:08:35.909 plugin-container[6658:121229] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 08:08:35 INFO - PID 6657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:08:35 INFO - PID 6657 | 2017-06-13 08:08:35.909 plugin-container[6658:121229] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x981f, name = 'com.apple.CFPasteboardClient' 08:08:35 INFO - PID 6657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:08:35 INFO - PID 6657 | 2017-06-13 08:08:35.909 plugin-container[6658:121229] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:08:36 INFO - PID 6657 | 2017-06-13 08:08:36.707 plugin-container[6659:121274] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 08:08:36 INFO - PID 6657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:08:36 INFO - PID 6657 | 2017-06-13 08:08:36.707 plugin-container[6659:121274] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960f, name = 'com.apple.CFPasteboardClient' 08:08:36 INFO - PID 6657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:08:36 INFO - PID 6657 | 2017-06-13 08:08:36.707 plugin-container[6659:121274] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:08:46 INFO - PID 6657 | RSS: Main: 214290432 08:08:46 INFO - PID 6657 | 08:08:48 INFO - PID 6657 | Cycle 1(1): loaded http://localhost:56895/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56895/tests/kraken/driver.html?testName=audio-beat-detection) 08:08:49 INFO - PID 6657 | RSS: Main: 228458496 08:08:49 INFO - PID 6657 | 08:08:52 INFO - PID 6657 | Cycle 1(1): loaded http://localhost:56895/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56895/tests/kraken/driver.html?testName=audio-dft) 08:08:53 INFO - PID 6657 | RSS: Main: 237682688 08:08:53 INFO - PID 6657 | 08:08:56 INFO - PID 6657 | Cycle 1(1): loaded http://localhost:56895/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56895/tests/kraken/driver.html?testName=audio-fft) 08:08:56 INFO - PID 6657 | RSS: Main: 242302976 08:08:56 INFO - PID 6657 | 08:08:58 INFO - PID 6657 | Cycle 1(1): loaded http://localhost:56895/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56895/tests/kraken/driver.html?testName=audio-oscillator) 08:08:59 INFO - PID 6657 | RSS: Main: 243441664 08:08:59 INFO - PID 6657 | 08:09:01 INFO - PID 6657 | Cycle 1(1): loaded http://localhost:56895/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56895/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:09:02 INFO - PID 6657 | RSS: Main: 244871168 08:09:02 INFO - PID 6657 | 08:09:04 INFO - PID 6657 | Cycle 1(1): loaded http://localhost:56895/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56895/tests/kraken/driver.html?testName=imaging-darkroom) 08:09:05 INFO - PID 6657 | RSS: Main: 248840192 08:09:05 INFO - PID 6657 | 08:09:08 INFO - PID 6657 | Cycle 1(1): loaded http://localhost:56895/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56895/tests/kraken/driver.html?testName=imaging-desaturate) 08:09:09 INFO - PID 6657 | RSS: Main: 249634816 08:09:09 INFO - PID 6657 | 08:09:12 INFO - PID 6657 | Cycle 1(1): loaded http://localhost:56895/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56895/tests/kraken/driver.html?testName=json-parse-financial) 08:09:12 INFO - PID 6657 | RSS: Main: 249565184 08:09:12 INFO - PID 6657 | 08:09:14 INFO - PID 6657 | Cycle 1(1): loaded http://localhost:56895/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56895/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:09:14 INFO - PID 6657 | RSS: Main: 249004032 08:09:14 INFO - PID 6657 | 08:09:16 INFO - PID 6657 | Cycle 1(1): loaded http://localhost:56895/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56895/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:09:17 INFO - PID 6657 | RSS: Main: 249749504 08:09:17 INFO - PID 6657 | 08:09:19 INFO - PID 6657 | Cycle 1(1): loaded http://localhost:56895/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56895/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:09:19 INFO - PID 6657 | RSS: Main: 248647680 08:09:19 INFO - PID 6657 | 08:09:22 INFO - PID 6657 | Cycle 1(1): loaded http://localhost:56895/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56895/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:09:23 INFO - PID 6657 | RSS: Main: 248635392 08:09:23 INFO - PID 6657 | 08:09:26 INFO - PID 6657 | Cycle 1(1): loaded http://localhost:56895/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56895/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:09:26 INFO - PID 6657 | RSS: Main: 248967168 08:09:26 INFO - PID 6657 | 08:09:28 INFO - PID 6657 | Cycle 1(1): loaded http://localhost:56895/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56895/tests/kraken/driver.html?testName=ai-astar) 08:09:28 INFO - PID 6657 | RSS: Main: 250544128 08:09:28 INFO - PID 6657 | 08:09:28 INFO - PID 6657 | __start_tp_report 08:09:28 INFO - PID 6657 | _x_x_mozilla_page_load 08:09:28 INFO - PID 6657 | _x_x_mozilla_page_load_details 08:09:28 INFO - PID 6657 | |i|pagename|runs| 08:09:28 INFO - PID 6657 | |0;ai-astar;103;115;107;119;164;166;168;101;117;116 08:09:28 INFO - PID 6657 | |1;audio-beat-detection;135;133;181;174;112;128;115;176;164;136 08:09:28 INFO - PID 6657 | |2;audio-dft;198;201;198;204;199;201;203;201;203;203 08:09:28 INFO - PID 6657 | |3;audio-fft;64;80;84;158;61;57;62;60;125;58 08:09:28 INFO - PID 6657 | |4;audio-oscillator;71;73;117;68;69;71;111;68;71;116 08:09:28 INFO - PID 6657 | |5;imaging-gaussian-blur;93;94;94;93;118;94;93;92;93;90 08:09:28 INFO - PID 6657 | |6;imaging-darkroom;97;100;98;98;98;100;98;99;98;99 08:09:28 INFO - PID 6657 | |7;imaging-desaturate;93;95;90;98;142;104;96;91;92;95 08:09:28 INFO - PID 6657 | |8;json-parse-financial;51;50;54;53;52;51;52;53;53;52 08:09:28 INFO - PID 6657 | |9;json-stringify-tinderbox;56;54;56;58;64;66;59;55;57;59 08:09:28 INFO - PID 6657 | |10;stanford-crypto-aes;81;80;79;81;80;81;81;78;77;98 08:09:28 INFO - PID 6657 | |11;stanford-crypto-ccm;134;133;135;135;133;134;143;137;150;161 08:09:28 INFO - PID 6657 | |12;stanford-crypto-pbkdf2;179;177;201;213;200;181;186;177;181;204 08:09:28 INFO - PID 6657 | |13;stanford-crypto-sha256-iterative;67;62;64;67;71;67;62;82;70;66 08:09:28 INFO - PID 6657 | __end_tp_report 08:09:28 INFO - PID 6657 | __start_cc_report 08:09:28 INFO - PID 6657 | _x_x_mozilla_cycle_collect,1022 08:09:28 INFO - PID 6657 | __end_cc_report 08:09:28 INFO - PID 6657 | __startTimestamp1497366568991__endTimestamp 08:09:28 INFO - PID 6657 | 08:09:28 INFO - PID 6657 | ------- Summary: start ------- 08:09:28 INFO - PID 6657 | Number of tests: 14 08:09:28 INFO - PID 6657 | 08:09:28 INFO - PID 6657 | [#0] ai-astar Cycles:10 Average:127.60 Median:118.00 stddev:27.18 (23.0%) stddev-sans-first:27.33 08:09:28 INFO - PID 6657 | Values: 103.0 115.0 107.0 119.0 164.0 166.0 168.0 101.0 117.0 116.0 08:09:28 INFO - PID 6657 | 08:09:28 INFO - PID 6657 | [#1] audio-beat-detection Cycles:10 Average:145.40 Median:150.00 stddev:25.95 (17.3%) stddev-sans-first:27.25 08:09:28 INFO - PID 6657 | Values: 135.0 133.0 181.0 174.0 112.0 128.0 115.0 176.0 164.0 136.0 08:09:28 INFO - PID 6657 | 08:09:28 INFO - PID 6657 | [#2] audio-dft Cycles:10 Average:201.10 Median:202.00 stddev:2.18 (1.1%) stddev-sans-first:2.01 08:09:28 INFO - PID 6657 | Values: 198.0 201.0 198.0 204.0 199.0 201.0 203.0 201.0 203.0 203.0 08:09:28 INFO - PID 6657 | 08:09:28 INFO - PID 6657 | [#3] audio-fft Cycles:10 Average:80.90 Median:61.50 stddev:34.11 (55.5%) stddev-sans-first:35.63 08:09:29 INFO - PID 6657 | Values: 64.0 80.0 84.0 158.0 61.0 57.0 62.0 60.0 125.0 58.0 08:09:29 INFO - PID 6657 | 08:09:29 INFO - PID 6657 | [#4] audio-oscillator Cycles:10 Average:83.50 Median:70.00 stddev:21.61 (30.9%) stddev-sans-first:22.45 08:09:29 INFO - PID 6657 | Values: 71.0 73.0 117.0 68.0 69.0 71.0 111.0 68.0 71.0 116.0 08:09:29 INFO - PID 6657 | 08:09:29 INFO - PID 6657 | [#5] imaging-gaussian-blur Cycles:10 Average:95.40 Median:93.00 stddev:8.03 (8.6%) stddev-sans-first:8.47 08:09:29 INFO - PID 6657 | Values: 93.0 94.0 94.0 93.0 118.0 94.0 93.0 92.0 93.0 90.0 08:09:29 INFO - PID 6657 | 08:09:29 INFO - PID 6657 | [#6] imaging-darkroom Cycles:10 Average:98.50 Median:98.00 stddev:0.97 (1.0%) stddev-sans-first:0.87 08:09:29 INFO - PID 6657 | Values: 97.0 100.0 98.0 98.0 98.0 100.0 98.0 99.0 98.0 99.0 08:09:29 INFO - PID 6657 | 08:09:29 INFO - PID 6657 | [#7] imaging-desaturate Cycles:10 Average:99.60 Median:94.00 stddev:15.43 (16.4%) stddev-sans-first:16.18 08:09:29 INFO - PID 6657 | Values: 93.0 95.0 90.0 98.0 142.0 104.0 96.0 91.0 92.0 95.0 08:09:29 INFO - PID 6657 | 08:09:29 INFO - PID 6657 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.50 stddev:1.20 (2.3%) stddev-sans-first:1.20 08:09:29 INFO - PID 6657 | Values: 51.0 50.0 54.0 53.0 52.0 51.0 52.0 53.0 53.0 52.0 08:09:29 INFO - PID 6657 | 08:09:29 INFO - PID 6657 | [#9] json-stringify-tinderbox Cycles:10 Average:58.40 Median:58.50 stddev:3.86 (6.6%) stddev-sans-first:4.00 08:09:29 INFO - PID 6657 | Values: 56.0 54.0 56.0 58.0 64.0 66.0 59.0 55.0 57.0 59.0 08:09:29 INFO - PID 6657 | 08:09:29 INFO - PID 6657 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:81.00 stddev:5.93 (7.3%) stddev-sans-first:6.28 08:09:29 INFO - PID 6657 | Values: 81.0 80.0 79.0 81.0 80.0 81.0 81.0 78.0 77.0 98.0 08:09:29 INFO - PID 6657 | 08:09:29 INFO - PID 6657 | [#11] stanford-crypto-ccm Cycles:10 Average:139.50 Median:136.00 stddev:9.29 (6.8%) stddev-sans-first:9.64 08:09:29 INFO - PID 6657 | Values: 134.0 133.0 135.0 135.0 133.0 134.0 143.0 137.0 150.0 161.0 08:09:29 INFO - PID 6657 | 08:09:29 INFO - PID 6657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.90 Median:193.00 stddev:13.26 (6.9%) stddev-sans-first:13.47 08:09:29 INFO - PID 6657 | Values: 179.0 177.0 201.0 213.0 200.0 181.0 186.0 177.0 181.0 204.0 08:09:29 INFO - PID 6657 | 08:09:29 INFO - PID 6657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.00 stddev:5.81 (8.7%) stddev-sans-first:6.15 08:09:29 INFO - PID 6657 | Values: 67.0 62.0 64.0 67.0 71.0 67.0 62.0 82.0 70.0 66.0 08:09:29 INFO - PID 6657 | -------- Summary: end -------- 08:09:29 INFO - PID 6657 | 08:09:29 INFO - TEST-INFO | 6657: exit 0 08:09:29 INFO - TEST-OK | kraken | took 88776ms 08:09:29 INFO - SUITE-END | took 1232s 08:09:30 INFO - Completed test suite (00:20:32) 08:09:30 INFO - javascript benchmark 08:09:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8345.191791191375, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3418.572678415198, 3452.6719961603653, 3498.6851183393496, 3560.7151427885765, 3579.123114836864, 6108.190302030429, 6148.754049838007, 6176.474999625032, 6189.0, 6220.533459990496, 6182.659953702545, 6194.163787888634, 6204.0, 6206.224221972254, 6210.503159747221, 5386.138217885129, 5388.191771234307, 5441.945580544183, 5468.398476167618, 5532.419095994903, 4525.977370113144, 5296.258523806651, 5440.972795136038, 5445.945540544583, 5483.890322193553, 5334.066538355792, 5361.2226227197225, 5384.300040994665, 5385.515303622673, 5407.837764867021, 5456.372517160537, 5485.341758988907, 5515.172724091378, 5519.530839878616, 5526.613137080406, 5377.7311134443125, 5396.4333744957, 5447.945520544784, 5479.068558345091, 5482.506574408282, 6126.938730612681, 6205.100260462208, 6223.941929871932, 6224.06639004153, 6225.470834979033, 6424.164858568403, 6700.530962832604, 6722.193336799566, 6729.966350168267, 6745.460363170934, 6523.7064332105165, 6550.639714815683, 6659.500537459661, 6741.966290168518, 6788.151481064867, 6255.343188965138, 6264.592801467936, 6293.842653933688, 6320.146780184617, 6349.206349206349, 6708.262091169968, 6914.619695916773, 6931.133608298963, 6940.548864323803, 6941.409980151744, 6427.903581446282, 6437.388448097423, 6460.644664543402, 6473.385028422293, 6499.967500162469, 6764.188297404245, 6781.525293229525, 6789.0, 6814.114165158597, 6836.589804611739, 6721.66391680414, 6725.495587830932, 6727.562708423937, 6731.663416829237, 6738.0, 6449.967750161313, 6540.084388185658, 6541.8691626168165, 6733.3939945405145, 6737.8652426951185, 5383.084875571162, 5397.298351214317, 5398.541124004503, 5431.53831924283, 5448.155535891943, 4738.976305118453, 4816.759162041884, 4866.245731911559, 4867.610591152699, 4886.731229782325, 6222.533310001768, 6226.626402415779, 6276.874462510816, 6321.462675672619, 6347.809565713036, 6816.965915170393, 6834.829129271709, 6846.110005699227, 6849.965750171317, 6867.2102708188195, 6187.412195841388, 6276.058591211355, 6305.621662700252, 6319.55763096588, 6332.8416789579705, 5835.591508594358, 5899.675517846474, 5932.8813423732145, 5934.406559344117, 5942.61373010753, 6799.456043516507, 6837.35045170718, 6885.173779146434, 6938.410235130071, 6953.617551034539, 6592.110065141336, 6782.728690852311, 6790.151231096113, 6805.931940680728, 6810.523263371715, 6915.066466027022, 6931.09895713554, 6956.40870526011, 6961.0, 6964.477664175106, 6673.766418175534, 6689.732410703517, 6698.5980841149685, 6703.0, 6703.29615390377, 6381.361863813581, 6556.934430655632, 6824.965875170592, 6831.59010459374, 6879.931200687929, 5657.943420565906, 6401.455876250571, 6405.51958603116, 6451.935480645134, 6470.935290647033, 6095.451409373178, 6493.837654058686, 6574.704138313883, 6584.473242140713, 6587.176602924635, 6153.107799368977, 6285.59143655661, 6302.590331628429, 6456.192975878015, 6484.967575162094, 6396.776112836214, 6454.225492940877, 6486.902696459462, 6509.056186852785, 6516.902246466212, 6458.515611329263, 6469.644169570719, 6491.902621460587, 6496.70764815575, 6501.317361676955], "value": 6122.670154686782, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [836.4563034027881, 852.5524099847581, 1242.7079636285478, 1285.4665313894734, 1288.4073326269922, 36083.4587481188, 39813.203735925264, 39885.0, 39905.802825915234, 40045.998850028765, 34101.0, 40781.59218407815, 40785.59214407855, 40856.18287634246, 40980.79509602455, 7784.571848548328, 7905.960470197641, 7924.603769811486, 7930.643121059537, 7940.483868548555, 6770.72917083316, 7931.087924888625, 8292.295154911839, 8294.253517183452, 8300.418970672055, 8321.043080045782, 8366.58167091643, 8378.162183781604, 8399.076101628847, 8410.705625303115, 8301.418900676956, 8358.83282334356, 8367.497950123012, 8376.371772117116, 8381.580920953958, 8126.553039582821, 8158.428909976304, 8191.262786349257, 8254.711085112052, 8278.751637450887, 34686.0, 37461.62538374608, 37638.43542346867, 37665.246695065944, 37765.244695106216, 3129.7339726123305, 3566.1797786509246, 3573.821308934569, 3586.085548185196, 3587.121155316938, 27178.456430871272, 29398.559021614692, 30815.383692326028, 30824.229394265098, 30872.53691194634, 3049.832259225742, 3147.1817327494787, 3165.5884734984534, 3176.65056843747, 3205.743540516753, 288854.55572722, 300294.0, 315138.0, 317290.4135479331, 318438.0, 38365.0, 40321.39517907234, 49264.0, 49715.75142124266, 49731.0, 41377.586224137376, 41507.58492415098, 41534.79232603818, 50697.0, 51542.742286288325, 15648.608784780472, 15732.370705171665, 15865.04809711421, 15906.44327448557, 15918.283677234553, 12670.936645316715, 12798.552050678181, 12820.846123848898, 12864.292463914575, 12868.420921058574, 13874.375653095634, 13901.304934753221, 13913.0, 13925.094868833494, 14031.649208769659, 6112.724927378279, 6142.938570614326, 6144.969275153596, 6147.692615369214, 6157.8460538486, 896.3322324868016, 954.4464210757883, 975.6926568131016, 989.7921436498272, 1054.0566193257146, 22898.771012289664, 23043.0, 23467.882660586587, 23684.7631523686, 24264.51470970606, 1014.7108074198818, 1082.91740139673, 1127.6560649002133, 1131.479519421059, 1132.7168207948014, 12069.57756478519, 13792.93103534496, 15694.921525392529, 15901.363945442054, 15970.520884373487, 332050.0, 332887.0, 333605.33197333856, 335008.0, 336316.0, 56295.59261018507, 56331.71834140803, 56511.15233271504, 56614.7169264151, 56638.43361566414, 1455.4105587237186, 1527.4348491058158, 1553.6038310231045, 1555.2146166186003, 1560.6254498920405, 22948.88525557428, 27293.181204563894, 28912.0, 29149.708502915546, 29474.705252947195, 39533.0, 39659.60340396559, 39767.60232397639, 39773.40339894962, 39774.0, 255974.0, 258344.0, 287054.0, 288008.0, 288996.0, 29797.10608681743, 30377.0, 30384.392312154076, 35858.28283434369, 35992.460113098845, 37346.813265933495, 37410.251794963406, 38424.6157538421, 42105.36841947435, 42835.0, 28569.714302857537, 28703.569446457903, 29838.10485685433, 30657.386852262385, 31236.219094522818, 29451.263718407212, 29973.250668733457, 30302.393952121274, 30663.08010759681, 30832.53751193689, 36040.819795900854, 36136.0, 36574.268514630094, 37165.07087322839, 37221.813890930374, 29139.417211656073, 30079.0, 31044.91342802991, 32101.59238057202, 32504.512432313062, 18745.6250874979, 23304.883475582512, 23528.29415117549, 25123.497530049663, 25202.117925872506], "value": 17673.9074179736, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.96174975176757, 506.64281681414604, 514.6886133888997, 527.765144510693, 533.5624787674107, 21891.0, 22552.88723556382, 22709.432264193405, 23025.654615180745, 23169.420764480874, 21614.027368768402, 23806.5238695226, 24116.276511704633, 24940.004799616032, 24942.625860612105, 6504.577202481835, 6612.70242839071, 6645.3354664533645, 6660.9000864987065, 6662.666866656672, 6114.480269177126, 6362.872742545146, 6390.840228994266, 6431.807045788634, 6577.112089867855, 6386.744530218785, 6395.8401039973905, 6403.871922561546, 6404.615723056631, 6409.038644203383, 6347.873042539147, 6401.391867772554, 6404.583702059398, 6424.293327733945, 6428.967855160718, 5792.420757924216, 5798.275215598051, 5827.592068555203, 5867.735951882176, 5873.20711703923, 24367.634485482733, 28166.859165704245, 28571.85714071437, 29529.114126576238, 30119.849400753075, 2227.855189412683, 2295.173737454515, 2295.5638428698494, 2298.310506847939, 2299.3906614747107, 18513.35203267879, 19049.523761905926, 19099.854008759517, 19114.904425477922, 19124.904375478032, 2144.6246906791407, 2180.607490651682, 2203.3610253026604, 2208.3595757230382, 2220.367195349334, 145119.0, 154964.0, 155062.0, 155520.0, 177969.0, 13790.448382064806, 14526.346314415876, 14536.563903082924, 14892.140564233849, 15104.320305586274, 20039.0, 20049.997500124937, 20175.39473815788, 20211.292604758764, 20263.898680506503, 7445.8883116753295, 9270.609408588765, 9688.079632434907, 9903.950480247553, 10126.949365253275, 8322.750317490485, 8788.209061184523, 9010.64840273951, 9466.810663786824, 9484.952575237217, 8801.603927823264, 8899.466032038099, 8965.0, 9003.549822508849, 9019.503927284062, 2152.9127790465714, 2536.961945570818, 2550.8214424990074, 2572.691277046729, 2683.7316268373397, 929.0894922975522, 930.8277968575835, 930.9813803723888, 967.8983706710694, 992.712113487095, 20390.898045509974, 20614.484637883874, 20650.896745516176, 21626.024385368968, 21759.564808703737, 986.452518852044, 1006.3207335048813, 1013.2470825471975, 1030.8917563655857, 1042.4162469017374, 10447.373157610567, 10564.524596393027, 10569.841452378221, 10683.771366292976, 11321.094312454983, 499514.0, 521875.0, 531252.0, 534348.0, 535213.0, 107478.0, 142003.86994195095, 146426.26786865998, 146749.0, 147565.2621736906, 980.2403137568441, 1025.0, 1029.6499190275342, 1148.8851114888612, 1153.6308381317897, 18616.2553497864, 19381.4185574433, 19408.029598519734, 20853.957302134528, 21860.89069554642, 55815.0, 60287.69856150867, 61386.77226455357, 62552.0, 64174.0, 77053.0, 78488.21511784809, 79764.80352794829, 80728.59635701784, 81192.7821082696, 11596.304221746723, 11629.476673549541, 11683.18217724751, 11749.76500470003, 11795.705107372385, 15771.818386830177, 17941.820581794014, 18334.449966501026, 18603.255869765057, 18604.97672627964, 14033.368498417392, 14905.105693658415, 15095.849041509884, 15098.39606415731, 15155.469558565397, 12802.807957880825, 13030.609081727564, 13108.147970381895, 13234.941204703407, 13649.249291289074, 14959.626009349853, 15039.172845493604, 15270.23648817577, 15285.235738213267, 15318.46385376551, 11341.09271258281, 11495.885041149482, 11496.712582185512, 11498.94250528742, 11500.022498087756, 14059.297035148405, 14867.256637167882, 15236.619084522976, 15262.08427494354, 15334.846651533342], "value": 11403.829577757086, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1410.132768347466, 1455.3148794770805, 1469.7433694191466, 1480.0527662296126, 1484.5991582272786, 13462.798058029126, 14110.506132285373, 14114.505992290271, 14118.364673589687, 14160.29198540074, 13288.534901278457, 14075.92962035191, 14149.36327865246, 14152.150870947724, 14183.149011059344, 2664.906728264516, 2734.4531093781225, 2736.2201772494745, 2739.1782465260444, 2743.1222008957143, 2710.0650275654966, 2801.145650576573, 2813.606095146681, 2821.4639218548514, 2830.193394882459, 2780.416112616353, 2784.1647505748297, 2805.424887897976, 2808.7612552932924, 2817.760490358322, 2737.096758069832, 2752.862356882158, 2782.1514438096374, 2791.4556661451024, 2799.0623141247706, 2758.089830355978, 2772.209920172761, 2791.637087178674, 2795.412963277694, 2809.311718628949, 13538.255395953218, 13650.658733531642, 13678.863211367858, 13699.520516781864, 13705.0, 6584.7366105356205, 7580.0, 7809.258120478546, 7833.412494062968, 7839.059312882434, 4596.47140578831, 4829.323894654751, 4892.339534162914, 4922.507749225048, 4932.778024988884, 1404.353997161307, 1404.9157050576998, 1409.415092736513, 1423.9715205695932, 1425.0024982512282, 20789.792102078936, 20800.0, 20901.790982090137, 20907.05918233683, 20938.476538086517, 6623.6688165592495, 6703.530752847254, 6870.931290687029, 6881.277465866012, 6900.171979362408, 11557.479913403917, 11796.646100616996, 14177.291135443187, 14278.785818212735, 14287.928560357132, 7377.26227377258, 7470.4770666052855, 7526.623668816646, 7536.434767392468, 7537.0, 6103.38966103395, 6888.070110535014, 7089.822754431078, 7097.432205423554, 7133.429325653935, 5000.349954505964, 5190.065788158092, 5201.739913004335, 5244.685318880803, 5303.840884773463, 1275.9553415630407, 1526.5496678479876, 1546.381447421018, 1549.457689808558, 1566.772817941392, 819.2503858969096, 842.7977285451448, 853.3514528957912, 856.3448961544424, 872.7512658892185, 7057.435405167575, 7188.568685878864, 7372.741954031583, 7443.590602516805, 7447.88828167578, 685.3283781893772, 689.8930665746817, 707.2361849202848, 710.6695386645234, 712.6508011074536, 4575.153596584642, 4919.1883339248625, 4972.925406118911, 5018.949810501921, 5261.394939581997, 18234.45296641103, 18464.538386540175, 18792.624147516974, 18959.715604265948, 18999.0, 18331.083445827655, 18494.630107397777, 18529.629407412045, 18999.240030398905, 19039.714404283946, 9770.120689138012, 9782.364146330467, 9864.309498335191, 9867.605295788231, 9935.205183585296, 6967.268436814062, 7585.0, 7647.464677472746, 7810.882836757567, 8029.518228906284, 16715.247813848164, 16995.49013529596, 17198.31206751716, 17204.13979301055, 17239.22423490971, 16959.067251301298, 17203.22585483681, 17394.73907891357, 17876.195571199587, 17879.821201787814, 9837.262205334771, 9979.151772099453, 9983.500824958577, 10743.946280268548, 11242.931921467447, 12778.936105319413, 12791.616251512469, 12862.0, 12914.031447641652, 12932.288724120264, 9931.751706207402, 10059.0, 10252.38485690861, 10291.0, 10590.0, 8359.874601881098, 9252.074792520694, 9892.208623310262, 10032.097111260022, 10186.286959912735, 9305.348625596143, 9383.390079644802, 9408.858867116862, 9444.150026497648, 9449.622015119317, 6504.85920757538, 6710.228323742636, 6839.965800170968, 7080.672084120455, 7153.785386438415, 6893.724251029903, 7014.298570142945, 7175.569465832067, 7293.234210408043, 7297.817554561178], "value": 6147.758072756208, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.65886020302133, 427.5105004769537, 432.20474327237895, 468.5431704088512, 472.2632692998922, 6431.588154442078, 6509.609423434597, 6538.248101468333, 6551.443127334176, 6561.1470508833845, 6119.510439164867, 6315.778947736831, 6324.652144132071, 6345.873082538346, 6351.9682401587925, 3964.425158352037, 4760.976195119037, 4789.449213340458, 4799.472058073609, 4809.807607695688, 4758.287016673995, 4800.183968725325, 4804.423469183703, 4812.23004319309, 4834.758262086899, 4552.817887284505, 4745.59662428694, 4768.523147685221, 4789.156174872682, 4797.0, 4716.575508204278, 4730.195866702668, 4737.820602522844, 4747.335373047776, 4755.262934245197, 3634.164746802874, 4172.958270417287, 4185.539590645027, 4188.350805625133, 4189.916201675949, 4438.201123797715, 4758.479444527712, 5571.913476872011, 6157.507399408037, 6251.281102673205, 5403.810866619689, 5612.354579223401, 5615.6630602164005, 5632.690202038886, 5634.690092044936, 6027.547933904974, 6701.08502801283, 6814.557053791537, 6819.454443644546, 6835.384815366591, 3979.960200397988, 4759.524047595248, 4762.476127625959, 4769.737664428455, 4794.065157294329, 5750.6262092963825, 6983.755568555127, 7132.6077065761365, 7267.345938865475, 7285.380742636882, 4865.70805751656, 4874.341963834908, 4876.536729010738, 4903.362562866804, 4905.950940490549, 4972.0, 4976.477469865685, 4983.626228032912, 4989.90020199594, 5009.774560144802, 4778.402699662543, 5667.716614169275, 5672.0, 5697.774978379668, 5740.483356497935, 5992.071228959431, 6861.416779573692, 6879.208890977501, 6900.516963812485, 7109.928900710926, 1579.5893067802224, 1640.8277130901322, 1675.7653928450027, 1703.6677847819556, 1703.7444383342597, 915.5651065743851, 1058.2539309786582, 1067.6636859389423, 1116.1405717597404, 1162.5756598841363, 6261.467775239155, 6397.936020639827, 6405.743770249138, 6526.172671651945, 6580.76967306142, 7071.61106139157, 7087.547052854153, 7113.53762005468, 7114.608696521637, 7143.67841949241, 6190.907136392958, 6479.546431749733, 6501.837454063686, 6506.3818937200895, 6513.609183448913, 3140.8586613602442, 3402.5066365376883, 3495.213576945167, 3657.6708096271464, 3676.9080772980888, 7221.8555628887125, 7231.566106033655, 7313.268673132644, 7337.339639432476, 7344.008558844487, 5766.538676905838, 7173.210946795848, 7239.9276007240305, 7286.635668216568, 7339.88990165148, 6388.6903184847, 6551.934480655133, 6877.140357455318, 7261.237570055068, 7317.926820731725, 5655.943440565542, 7056.294370562902, 7065.682044307916, 7115.82210444743, 7145.535540189871, 5835.803660249554, 6946.270641582763, 6946.930530694628, 6957.599628128288, 6976.0, 7016.614086225102, 7257.782266532013, 7342.632868356668, 7358.558486490828, 7374.705011799468, 3925.5878132795647, 4746.02706445171, 4754.857354279377, 4846.321514987975, 4861.902761944671, 6850.4862135341045, 7126.144862616519, 7150.641378138345, 7191.82020449472, 7215.314545118205, 6811.8978215325815, 6833.3166683331265, 6854.725810967506, 6862.862742745217, 6887.7244910203035, 6877.38103570661, 6878.65606719672, 6881.41507971828, 6882.105326307548, 6916.204636466669, 5746.655200687972, 6685.064091027358, 6766.898496522655, 6773.559718618274, 6776.864462710817, 5735.569832262514, 5956.344802071682, 6060.939335616337, 6823.181218253642, 6881.346272104142, 6647.667616619053, 6662.201205674538, 6723.428508576826, 6752.662366881538, 6764.763233286812], "value": 5037.654499669667, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.6384790692091, 840.3697227079691, 840.3991146330376, 851.0680804519052, 861.655337864854, 16974.0, 16999.745003824937, 17186.312547498084, 17205.655886882254, 17225.397111101112, 16826.24281907314, 17910.462686119405, 18076.09619519022, 18300.176492057857, 18318.175682094305, 928.7724507495673, 936.372630337672, 937.8921424036221, 946.4368700623119, 952.333366643349, 809.6437567470324, 947.7535840681438, 961.3799099580766, 964.4117088575963, 968.602872822143, 946.857971304303, 979.7844474215661, 989.1098011789411, 998.7852611688486, 1014.0569270578359, 905.6286922361833, 983.0709979069782, 994.2195376629384, 996.2229461020415, 996.566493575297, 634.3212762344293, 722.0793488302404, 731.7950973727364, 734.4711807498593, 737.5906371963558, 14577.396486386595, 16884.746728799077, 16953.237104330336, 17033.233504492327, 17133.657326853394, 17213.483595492155, 17601.55996100095, 17694.911525442418, 17805.931644101267, 17951.640967180712, 14505.057171283834, 15081.622959425993, 15125.941184117117, 15126.0, 15163.924180379137, 3660.798656073915, 3693.593704692482, 3706.7405281630295, 3708.758930669525, 3718.9256214875822, 147528.0, 148038.0, 148439.0, 150713.9999999989, 152512.2374388121, 13630.931845340909, 13669.248191349372, 13861.306934653227, 13867.237301948287, 14142.50501232452, 25832.74167258341, 26664.73335266661, 26803.0, 26885.059022934103, 26915.92336306526, 8130.0, 8244.464109832841, 8302.501849888906, 8317.916820831835, 8346.457480263764, 4081.9795901020307, 4121.9587804122175, 4135.400366946836, 4179.059711564873, 4187.078842654672, 4601.792919318638, 4655.162070827216, 4733.857984260478, 4755.952440475551, 4791.520847915181, 864.0452300208179, 867.1631875240423, 898.4878619187001, 1032.2155162076876, 1046.1108058150512, 638.1321402892063, 645.2450632759683, 668.1514476614672, 679.8408713144078, 689.8137502874196, 10578.206634502443, 11173.050290725218, 11745.647630571097, 12031.06450336381, 12123.75752484963, 609.2141137932018, 616.6978180691428, 626.5614070150913, 639.3191251317425, 648.18652590998, 4458.888527786831, 4581.854536365909, 4915.26271059344, 5218.347706536683, 5311.787528498817, 134020.0, 148712.0, 156824.21587892214, 158627.0, 161348.0, 126582.0, 127619.89520419079, 128869.0, 129005.0, 130797.69202308045, 108619.99999999841, 111237.0, 111769.88230117595, 112695.0, 112849.30726039116, 11078.11375090007, 11091.889081109086, 11133.276337038067, 11555.306681599131, 11760.840017326282, 38111.0, 45548.0, 46266.53733462622, 46665.3000205004, 48176.79558010938, 76845.23154768381, 79649.8052529201, 83908.0, 87613.56193219248, 88115.0, 15548.844511555191, 17637.647247055243, 18080.547986300448, 18190.636187276446, 18496.44510664682, 18276.634467310847, 18955.43133705936, 19077.80922190816, 19211.90394048021, 19591.0, 15366.923165384549, 15579.143147127017, 15650.530484085495, 15792.289346979183, 18264.908675456536, 16144.596385090466, 17123.97256164634, 17767.822321776617, 18233.179506921955, 18710.06449677538, 17167.914160429118, 17713.822861771216, 17820.10899455048, 17915.552111197325, 18455.72316415228, 11088.223824332543, 11568.421578920852, 11913.880861191277, 12224.266544007389, 12242.510299588273, 11984.221025633306, 12571.94491916651, 13066.934665326613, 13088.411021503865, 13186.736265274832], "value": 8837.334602534258, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 107.0, 119.0, 164.0, 166.0, 168.0, 101.0, 117.0, 116.0], "value": 127.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 133.0, 181.0, 174.0, 112.0, 128.0, 115.0, 176.0, 164.0, 136.0], "value": 145.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 201.0, 198.0, 204.0, 199.0, 201.0, 203.0, 201.0, 203.0, 203.0], "value": 201.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 80.0, 84.0, 158.0, 61.0, 57.0, 62.0, 60.0, 125.0, 58.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 117.0, 68.0, 69.0, 71.0, 111.0, 68.0, 71.0, 116.0], "value": 83.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 93.0, 118.0, 94.0, 93.0, 92.0, 93.0, 90.0], "value": 95.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 100.0, 98.0, 98.0, 98.0, 100.0, 98.0, 99.0, 98.0, 99.0], "value": 98.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 90.0, 98.0, 142.0, 104.0, 96.0, 91.0, 92.0, 95.0], "value": 99.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 54.0, 53.0, 52.0, 51.0, 52.0, 53.0, 53.0, 52.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 56.0, 58.0, 64.0, 66.0, 59.0, 55.0, 57.0, 59.0], "value": 58.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 79.0, 81.0, 80.0, 81.0, 81.0, 78.0, 77.0, 98.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 133.0, 135.0, 135.0, 133.0, 134.0, 143.0, 137.0, 150.0, 161.0], "value": 139.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 177.0, 201.0, 213.0, 200.0, 181.0, 186.0, 177.0, 181.0, 204.0], "value": 189.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 62.0, 64.0, 67.0, 71.0, 67.0, 62.0, 82.0, 70.0, 66.0], "value": 67.8, "unit": "score"}]}]} 08:09:30 INFO - Return code: 0 08:09:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:09:30 INFO - # TBPL SUCCESS # 08:09:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:09:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:09:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:09:30 INFO - 2017-06-13 08:09:30.361 screenresolution[6665:121658] starting screenresolution argv=screenresolution get 08:09:30 INFO - 2017-06-13 08:09:30.363 screenresolution[6665:121658] Display 0: 1600x1200x32@60 08:09:30 INFO - 2017-06-13 08:09:30.369 screenresolution[6666:121662] starting screenresolution argv=screenresolution list 08:09:30 INFO - Available Modes on Display 0 08:09:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:09:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:09:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:09:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:09:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:09:30 INFO - Intel Iris: 08:09:30 INFO - Chipset Model: Intel Iris 08:09:30 INFO - Type: GPU 08:09:30 INFO - Bus: Built-In 08:09:30 INFO - VRAM (Dynamic, Max): 1536 MB 08:09:30 INFO - Vendor: Intel (0x8086) 08:09:30 INFO - Device ID: 0x0a2e 08:09:30 INFO - Revision ID: 0x0009 08:09:30 INFO - Displays: 08:09:30 INFO - Display: 08:09:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:09:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:09:30 INFO - Display Serial Number: 1600x1200 60 08:09:30 INFO - Main Display: Yes 08:09:30 INFO - Mirror: Off 08:09:30 INFO - Online: Yes 08:09:30 INFO - Rotation: Supported 08:09:30 INFO - Return code: 0 08:09:30 INFO - Running post-action listener: _resource_record_post_action 08:09:30 INFO - [mozharness: 2017-06-13 15:09:30.609733Z] Finished run-tests step (success) 08:09:30 INFO - Running post-run listener: _resource_record_post_run 08:09:30 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 3514368; Write bytes: 560525312; Read time: 39; Write time: 1758 08:09:30 INFO - TinderboxPrint: CPU usage
53.0% 08:09:30 INFO - TinderboxPrint: I/O read bytes / time
3,514,368 / 39 08:09:30 INFO - TinderboxPrint: I/O write bytes / time
560,525,312 / 1,758 08:09:30 INFO - TinderboxPrint: CPU idle
2,358.5 (47.0%) 08:09:30 INFO - TinderboxPrint: CPU system
1,016.5 (20.2%) 08:09:30 INFO - TinderboxPrint: CPU user
1,646.7 (32.8%) 08:09:30 INFO - TinderboxPrint: Swap in / out
247,779,328 / 0 08:09:30 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 227590656; Write bytes: 225386496; Read time: 16880; Write time: 489 08:09:30 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:09:30 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 3457024; Write bytes: 316694528; Read time: 38; Write time: 1217 08:09:31 INFO - Running post-run listener: _upload_blobber_files 08:09:31 INFO - Blob upload gear active. 08:09:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:09:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:09:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:09:31 INFO - (blobuploader) - INFO - Open directory for files ... 08:09:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:09:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:09:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:32 INFO - (blobuploader) - INFO - Done attempting. 08:09:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:09:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:09:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:33 INFO - (blobuploader) - INFO - Done attempting. 08:09:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:09:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:09:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:35 INFO - (blobuploader) - INFO - Done attempting. 08:09:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:09:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:09:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:37 INFO - (blobuploader) - INFO - Done attempting. 08:09:37 INFO - (blobuploader) - INFO - Iteration through files over. 08:09:37 INFO - Return code: 0 08:09:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:09:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:09:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e07ddfac0626f8c5ee3bbd2e8cfba36f40f9583642b71c34bae8b04467552555b10cb06675ee973894efa68f4596384146226bdc76e21d2885703461a8d6400", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc49663c8cf369fabbacdc8d641807ce77e6417fd1139af96c3c45f7fe470e7ab3c3fc2a546623e73bfa1c794e317fb6fee65c11a6aa9341522a3478b495e96a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/26208d99e1558a2416f6de4c7c199ebc43ce5741f3ecd3326847ef98a9c9d1b86aa7faadc95e548affcec3463cdd6f36d6c5471d657e5006ebc595f8c41652cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83668a0d3aa7d7c254fce52d68c3492cddc967ab386ab5e4458858db8dce2f78fed5164481d53ef959568f16bac21fcacd3eafa8b89758d2e4662a6bbaf77627"} 08:09:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:09:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:09:37 INFO - Contents: 08:09:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e07ddfac0626f8c5ee3bbd2e8cfba36f40f9583642b71c34bae8b04467552555b10cb06675ee973894efa68f4596384146226bdc76e21d2885703461a8d6400", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc49663c8cf369fabbacdc8d641807ce77e6417fd1139af96c3c45f7fe470e7ab3c3fc2a546623e73bfa1c794e317fb6fee65c11a6aa9341522a3478b495e96a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/26208d99e1558a2416f6de4c7c199ebc43ce5741f3ecd3326847ef98a9c9d1b86aa7faadc95e548affcec3463cdd6f36d6c5471d657e5006ebc595f8c41652cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83668a0d3aa7d7c254fce52d68c3492cddc967ab386ab5e4458858db8dce2f78fed5164481d53ef959568f16bac21fcacd3eafa8b89758d2e4662a6bbaf77627"} 08:09:37 INFO - Running post-run listener: copy_logs_to_upload_dir 08:09:37 INFO - Copying logs to upload dir... 08:09:37 INFO - mkdir: /builds/slave/test/build/upload/logs 08:09:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.218269 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-13 08:09:37.772999) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 08:09:37.776300) ========= 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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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/5e07ddfac0626f8c5ee3bbd2e8cfba36f40f9583642b71c34bae8b04467552555b10cb06675ee973894efa68f4596384146226bdc76e21d2885703461a8d6400", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc49663c8cf369fabbacdc8d641807ce77e6417fd1139af96c3c45f7fe470e7ab3c3fc2a546623e73bfa1c794e317fb6fee65c11a6aa9341522a3478b495e96a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/26208d99e1558a2416f6de4c7c199ebc43ce5741f3ecd3326847ef98a9c9d1b86aa7faadc95e548affcec3463cdd6f36d6c5471d657e5006ebc595f8c41652cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83668a0d3aa7d7c254fce52d68c3492cddc967ab386ab5e4458858db8dce2f78fed5164481d53ef959568f16bac21fcacd3eafa8b89758d2e4662a6bbaf77627"} build_url:https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011526 build_url: 'https://queue.taskcluster.net/v1/task/GkdKyHaVTk6QMRALtG5Wvg/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/5e07ddfac0626f8c5ee3bbd2e8cfba36f40f9583642b71c34bae8b04467552555b10cb06675ee973894efa68f4596384146226bdc76e21d2885703461a8d6400", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc49663c8cf369fabbacdc8d641807ce77e6417fd1139af96c3c45f7fe470e7ab3c3fc2a546623e73bfa1c794e317fb6fee65c11a6aa9341522a3478b495e96a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/26208d99e1558a2416f6de4c7c199ebc43ce5741f3ecd3326847ef98a9c9d1b86aa7faadc95e548affcec3463cdd6f36d6c5471d657e5006ebc595f8c41652cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83668a0d3aa7d7c254fce52d68c3492cddc967ab386ab5e4458858db8dce2f78fed5164481d53ef959568f16bac21fcacd3eafa8b89758d2e4662a6bbaf77627"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 08:09:37.810201) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 08:09:37.810554) ========= 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.r0B0gBnr19/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.YZ5cAK0mwj/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.005487 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 08:09:37.860030) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 08:09:37.860370) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 08:09:37.860666) ========= ========= Total master_lag: 0.18 =========