builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0161 starttime: 1495134849.03 results: success (0) buildid: 20170518105722 builduid: 64430f7d1ecd45f6ad6bf973772d643c revision: 54f218cec92ffcc2cc8160ed80c58ffe8b6c268d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 12:14:09.033928) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 12:14:09.034500) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 12:14:09.034782) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 12:14:09.060740) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 12:14:09.061161) ========= 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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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-05-18 12:14:09-- 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% 9.62M=0.001s 2017-05-18 12:14:09 (9.62 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087598 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 12:14:09.182775) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 12:14:09.183195) ========= 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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.028672 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 12:14:09.234560) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 12:14:09.234991) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 54f218cec92ffcc2cc8160ed80c58ffe8b6c268d --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 54f218cec92ffcc2cc8160ed80c58ffe8b6c268d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bNSL9wiSPK/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.KUCi4VAi7q/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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-05-18 12:14:09,319 truncating revision to first 12 chars 2017-05-18 12:14:09,319 Setting DEBUG logging. 2017-05-18 12:14:09,319 attempt 1/10 2017-05-18 12:14:09,319 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/54f218cec92f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-18 12:14:10,020 unpacking tar archive at: mozilla-beta-54f218cec92f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.974416 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 12:14:10.232361) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 12:14:10.232750) ========= script_repo_revision: 54f218cec92ffcc2cc8160ed80c58ffe8b6c268d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 12:14:10.233167) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 12:14:10.233512) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 12:14:10.252724) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-05-18 12:14:10.253141) ========= /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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 12:14:10 INFO - MultiFileLogger online at 20170518 12:14:10 in /builds/slave/test 12:14:10 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 12:14:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:14:10 INFO - {'append_to_log': False, 12:14:10 INFO - 'base_work_dir': '/builds/slave/test', 12:14:10 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:14:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:14:10 INFO - 'branch': 'Mozilla-Beta', 12:14:10 INFO - 'buildbot_json_path': 'buildprops.json', 12:14:10 INFO - 'config_files': ('talos/mac_config.py',), 12:14:10 INFO - 'default_actions': ('clobber', 12:14:10 INFO - 'read-buildbot-config', 12:14:10 INFO - 'download-and-extract', 12:14:10 INFO - 'populate-webroot', 12:14:10 INFO - 'create-virtualenv', 12:14:10 INFO - 'install', 12:14:10 INFO - 'run-tests'), 12:14:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:14:10 INFO - 'download_minidump_stackwalk': True, 12:14:10 INFO - 'download_symbols': 'ondemand', 12:14:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:14:10 INFO - 'tooltool.py': '/tools/tooltool.py', 12:14:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:14:10 INFO - '/tools/misc-python/virtualenv.py')}, 12:14:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:14:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:14:10 INFO - 'gecko_profile': False, 12:14:10 INFO - 'gecko_profile_interval': 0, 12:14:10 INFO - 'installer_path': 'installer.exe', 12:14:10 INFO - 'log_level': 'info', 12:14:10 INFO - 'log_name': 'talos', 12:14:10 INFO - 'log_to_console': True, 12:14:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:14:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:14:10 INFO - 'opt_config_files': (), 12:14:10 INFO - 'pip_index': False, 12:14:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:14:10 INFO - 'cmd': ('bash', 12:14:10 INFO - '-c', 12:14:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:14:10 INFO - 'enabled': True, 12:14:10 INFO - 'halt_on_failure': False, 12:14:10 INFO - 'name': 'check_screen_resolution'},), 12:14:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:14:10 INFO - 'cmd': ('bash', 12:14:10 INFO - '-c', 12:14:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:14:10 INFO - 'enabled': True, 12:14:10 INFO - 'halt_on_failure': False, 12:14:10 INFO - 'name': 'check_screen_resolution'},), 12:14:10 INFO - 'run_cmd_checks_enabled': True, 12:14:10 INFO - 'suite': 'dromaeojs-e10s', 12:14:10 INFO - 'system_bits': '32', 12:14:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:14:10 INFO - 'title': 't-yosemite-r7-0161', 12:14:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:14:10 INFO - 'use_talos_json': True, 12:14:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:14:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:14:10 INFO - 'work_dir': 'build'} 12:14:10 INFO - [mozharness: 2017-05-18 19:14:10.451629Z] Running clobber step. 12:14:10 INFO - Running pre-action listener: _resource_record_pre_action 12:14:10 INFO - Running main action method: clobber 12:14:10 INFO - rmtree: /builds/slave/test/build 12:14:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:14:12 INFO - Running post-action listener: _resource_record_post_action 12:14:12 INFO - [mozharness: 2017-05-18 19:14:12.998129Z] Finished clobber step (success) 12:14:12 INFO - [mozharness: 2017-05-18 19:14:12.998269Z] Running read-buildbot-config step. 12:14:12 INFO - Running pre-action listener: _resource_record_pre_action 12:14:12 INFO - Running main action method: read_buildbot_config 12:14:12 INFO - Using buildbot properties: 12:14:12 INFO - { 12:14:12 INFO - "project": "", 12:14:12 INFO - "product": "firefox", 12:14:12 INFO - "script_repo_revision": "production", 12:14:12 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 12:14:12 INFO - "repository": "", 12:14:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 12:14:12 INFO - "buildid": "20170518105722", 12:14:13 INFO - "pgo_build": "False", 12:14:13 INFO - "basedir": "/builds/slave/test", 12:14:13 INFO - "buildnumber": 13, 12:14:13 INFO - "slavename": "t-yosemite-r7-0161", 12:14:13 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:14:13 INFO - "platform": "yosemite_r7", 12:14:13 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:14:13 INFO - "branch": "mozilla-beta", 12:14:13 INFO - "repo_path": "releases/mozilla-beta", 12:14:13 INFO - "revision": "54f218cec92ffcc2cc8160ed80c58ffe8b6c268d", 12:14:13 INFO - "stage_platform": "macosx64", 12:14:13 INFO - "builduid": "64430f7d1ecd45f6ad6bf973772d643c", 12:14:13 INFO - "slavebuilddir": "test" 12:14:13 INFO - } 12:14:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 12:14:13 INFO - Running post-action listener: _resource_record_post_action 12:14:13 INFO - [mozharness: 2017-05-18 19:14:13.001846Z] Finished read-buildbot-config step (success) 12:14:13 INFO - [mozharness: 2017-05-18 19:14:13.001963Z] Running download-and-extract step. 12:14:13 INFO - Running pre-action listener: _resource_record_pre_action 12:14:13 INFO - Running main action method: download_and_extract 12:14:13 INFO - mkdir: /builds/slave/test/build/tests 12:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:13 INFO - trying https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 12:14:13 INFO - Downloading https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:14:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 12:14:14 INFO - Downloaded 1287 bytes. 12:14:14 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:14:14 INFO - Using the following test package requirements: 12:14:14 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:14:14 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 12:14:14 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:14:14 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:14:14 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 12:14:14 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:14:14 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 12:14:14 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:14:14 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:14:14 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 12:14:14 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:14:14 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:14:14 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 12:14:14 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:14:14 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 12:14:14 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:14:14 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 12:14:14 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:14:14 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 12:14:14 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 12:14:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:14:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:14:14 INFO - Fetch https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:14:15 INFO - Expected file size: 51225124 12:14:15 INFO - Obtained file size: 51225124 12:14:31 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:14:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:14:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:14:31 INFO - Fetch https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:14:33 INFO - Expected file size: 51225124 12:14:33 INFO - Obtained file size: 51225124 12:14:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 12:14:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 12:14:48 INFO - Fetch https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 12:14:49 INFO - Expected file size: 14404449 12:14:49 INFO - Obtained file size: 14404449 12:14:50 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')]} 12:14:50 INFO - trying https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:14:50 INFO - Downloading https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 12:14:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 12:14:51 INFO - Downloaded 59541956 bytes. 12:14:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:14:51 INFO - mkdir: /builds/slave/test/properties 12:14:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:14:51 INFO - Writing to file /builds/slave/test/properties/build_url 12:14:51 INFO - Contents: 12:14:51 INFO - build_url:https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:14:52 INFO - Running post-action listener: _resource_record_post_action 12:14:52 INFO - Running post-action listener: set_extra_try_arguments 12:14:52 INFO - [mozharness: 2017-05-18 19:14:52.532587Z] Finished download-and-extract step (success) 12:14:52 INFO - [mozharness: 2017-05-18 19:14:52.532727Z] Running populate-webroot step. 12:14:52 INFO - Running pre-action listener: _resource_record_pre_action 12:14:52 INFO - Running main action method: populate_webroot 12:14:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:14:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:14:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:14:52 INFO - '--tppagecycles', 12:14:52 INFO - '7'], 12:14:52 INFO - 'tests': ['tsvgm']}}, 12:14:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:14:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:14:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:14:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:14:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:14:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:14:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:14:52 INFO - 'talos_options': ['--disable-e10s'], 12:14:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:14:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:14:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:14:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:14:52 INFO - 'talos_options': ['--disable-e10s'], 12:14:52 INFO - 'tests': ['damp', 'tps']}, 12:14:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:14:52 INFO - 'tests': ['damp', 'tps']}, 12:14:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:14:52 INFO - 'tests': ['dromaeo_dom']}, 12:14:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:14:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:14:52 INFO - 'tests': ['basic_compositor_video']}, 12:14:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:14:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:14:52 INFO - 'tests': ['a11yr', 12:14:52 INFO - 'ts_paint', 12:14:52 INFO - 'tpaint', 12:14:52 INFO - 'sessionrestore', 12:14:52 INFO - 'sessionrestore_no_auto_restore', 12:14:52 INFO - 'tabpaint']}, 12:14:52 INFO - 'other-e10s': {'tests': ['a11yr', 12:14:52 INFO - 'ts_paint', 12:14:52 INFO - 'tpaint', 12:14:52 INFO - 'sessionrestore', 12:14:52 INFO - 'sessionrestore_no_auto_restore', 12:14:52 INFO - 'tabpaint']}, 12:14:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:14:52 INFO - 'ts_paint', 12:14:52 INFO - 'tpaint', 12:14:52 INFO - 'sessionrestore', 12:14:52 INFO - 'sessionrestore_no_auto_restore', 12:14:52 INFO - 'tabpaint']}, 12:14:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:14:52 INFO - 'ts_paint', 12:14:52 INFO - 'tpaint', 12:14:52 INFO - 'sessionrestore', 12:14:52 INFO - 'sessionrestore_no_auto_restore', 12:14:52 INFO - 'tabpaint']}, 12:14:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:14:52 INFO - 'tests': ['a11yr', 12:14:52 INFO - 'ts_paint', 12:14:52 INFO - 'tpaint', 12:14:52 INFO - 'sessionrestore', 12:14:52 INFO - 'sessionrestore_no_auto_restore', 12:14:52 INFO - 'tabpaint']}, 12:14:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:14:52 INFO - 'tests': ['a11yr', 12:14:52 INFO - 'ts_paint', 12:14:52 INFO - 'tpaint', 12:14:52 INFO - 'sessionrestore', 12:14:52 INFO - 'sessionrestore_no_auto_restore', 12:14:52 INFO - 'tabpaint']}, 12:14:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:14:52 INFO - 'tests': ['tsvgx', 12:14:52 INFO - 'tsvgr_opacity', 12:14:52 INFO - 'tart', 12:14:52 INFO - 'tscrollx', 12:14:52 INFO - 'cart', 12:14:52 INFO - 'tsvg_static']}, 12:14:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:14:52 INFO - 'tsvgr_opacity', 12:14:52 INFO - 'tart', 12:14:52 INFO - 'tscrollx', 12:14:52 INFO - 'cart', 12:14:52 INFO - 'tsvg_static']}, 12:14:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:14:52 INFO - 'talos_options': ['--disable-e10s'], 12:14:52 INFO - 'tests': ['tp5o']}, 12:14:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:14:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:14:52 INFO - 'talos_options': ['--disable-e10s', 12:14:52 INFO - '--xperf_path', 12:14:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:14:52 INFO - 'tests': ['tp5n']}, 12:14:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:14:52 INFO - 'talos_options': ['--xperf_path', 12:14:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:14:52 INFO - 'tests': ['tp5n']}}, 12:14:52 INFO - 'talos.zip': {'path': '', 12:14:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:14:52 INFO - Running post-action listener: _resource_record_post_action 12:14:52 INFO - [mozharness: 2017-05-18 19:14:52.550925Z] Finished populate-webroot step (success) 12:14:52 INFO - [mozharness: 2017-05-18 19:14:52.551035Z] Running create-virtualenv step. 12:14:52 INFO - Running pre-action listener: _resource_record_pre_action 12:14:52 INFO - Running main action method: create_virtualenv 12:14:52 INFO - Creating virtualenv /builds/slave/test/build/venv 12:14:52 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 12:14:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:14:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:14:52 INFO - Using real prefix '/tools/python27' 12:14:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:14:53 INFO - Installing distribute.............................................................................................................................................................................................done. 12:14:56 INFO - Installing pip.................done. 12:14:56 INFO - Return code: 0 12:14:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:14:56 INFO - Reading from file tmpfile_stdout 12:14:56 INFO - Output received: 12:14:56 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) 12:14:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14: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')]} 12:14:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:56 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 0x107c2ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6904a2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a08d30>, '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 0x10792fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079fdbe8>, '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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 12:14:56 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 12:14:56 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 12:14:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNSL9wiSPK/Render', 12:14:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:56 INFO - 'HOME': '/Users/cltbld', 12:14:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:56 INFO - 'LOGNAME': 'cltbld', 12:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:56 INFO - 'MOZ_NO_REMOTE': '1', 12:14:56 INFO - 'NO_EM_RESTART': '1', 12:14:56 INFO - 'PAGER': '/bin/cat', 12:14:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:56 INFO - 'PWD': '/builds/slave/test', 12:14:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:56 INFO - 'SHELL': '/bin/bash', 12:14:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUCi4VAi7q/Listeners', 12:14:56 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 12:14:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:56 INFO - 'USER': 'cltbld', 12:14:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:56 INFO - 'XPC_FLAGS': '0x0', 12:14:56 INFO - 'XPC_SERVICE_NAME': '0', 12:14:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:56 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 12:14:56 INFO - Cleaning up... 12:14:56 INFO - Return code: 0 12:14:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14: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')]} 12:14:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:56 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 0x107c2ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6904a2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a08d30>, '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 0x10792fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079fdbe8>, '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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 12:14:56 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 12:14:56 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 12:14:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNSL9wiSPK/Render', 12:14:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:56 INFO - 'HOME': '/Users/cltbld', 12:14:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:56 INFO - 'LOGNAME': 'cltbld', 12:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:56 INFO - 'MOZ_NO_REMOTE': '1', 12:14:56 INFO - 'NO_EM_RESTART': '1', 12:14:56 INFO - 'PAGER': '/bin/cat', 12:14:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:56 INFO - 'PWD': '/builds/slave/test', 12:14:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:56 INFO - 'SHELL': '/bin/bash', 12:14:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUCi4VAi7q/Listeners', 12:14:56 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 12:14:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:56 INFO - 'USER': 'cltbld', 12:14:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:56 INFO - 'XPC_FLAGS': '0x0', 12:14:56 INFO - 'XPC_SERVICE_NAME': '0', 12:14:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:57 INFO - Downloading/unpacking psutil>=3.1.1 12:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:14:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:14:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:14:58 INFO - Installing collected packages: psutil 12:14:58 INFO - Running setup.py install for psutil 12:14:59 INFO - building 'psutil._psutil_osx' extension 12:14:59 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 12:14:59 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 12:14:59 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 12:14:59 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 12:14:59 INFO - building 'psutil._psutil_posix' extension 12:14:59 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 12:14:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:14:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:14:59 INFO - ^ 12:15:00 INFO - 1 warning generated. 12:15:00 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 12:15:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:15:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:15:00 INFO - Successfully installed psutil 12:15:00 INFO - Cleaning up... 12:15:00 INFO - Return code: 0 12:15:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:15:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:15:00 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 0x107c2ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6904a2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a08d30>, '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 0x10792fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079fdbe8>, '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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 12:15:00 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 12:15:00 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 12:15:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNSL9wiSPK/Render', 12:15:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:15:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:15:00 INFO - 'HOME': '/Users/cltbld', 12:15:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:15:00 INFO - 'LOGNAME': 'cltbld', 12:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:15:00 INFO - 'MOZ_NO_REMOTE': '1', 12:15:00 INFO - 'NO_EM_RESTART': '1', 12:15:00 INFO - 'PAGER': '/bin/cat', 12:15:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:15:00 INFO - 'PWD': '/builds/slave/test', 12:15:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:15:00 INFO - 'SHELL': '/bin/bash', 12:15:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUCi4VAi7q/Listeners', 12:15:00 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 12:15:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:15:00 INFO - 'USER': 'cltbld', 12:15:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:15:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:15:00 INFO - 'XPC_FLAGS': '0x0', 12:15:00 INFO - 'XPC_SERVICE_NAME': '0', 12:15:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:15:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:15:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:15:00 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 12:15:00 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 12:15:00 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 12:15:00 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 12:15:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:15:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:15:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:15:01 INFO - Installing collected packages: mozsystemmonitor 12:15:01 INFO - Running setup.py install for mozsystemmonitor 12:15:02 INFO - Successfully installed mozsystemmonitor 12:15:02 INFO - Cleaning up... 12:15:02 INFO - Return code: 0 12:15:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:15:02 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')]} 12:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:15:02 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')]} 12:15:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:15:02 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 0x107c2ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6904a2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a08d30>, '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 0x10792fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079fdbe8>, '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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 12:15:02 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 12:15:02 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 12:15:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNSL9wiSPK/Render', 12:15:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:15:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:15:02 INFO - 'HOME': '/Users/cltbld', 12:15:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:15:02 INFO - 'LOGNAME': 'cltbld', 12:15:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:15:02 INFO - 'MOZ_NO_REMOTE': '1', 12:15:02 INFO - 'NO_EM_RESTART': '1', 12:15:02 INFO - 'PAGER': '/bin/cat', 12:15:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:15:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:15:02 INFO - 'PWD': '/builds/slave/test', 12:15:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:15:02 INFO - 'SHELL': '/bin/bash', 12:15:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUCi4VAi7q/Listeners', 12:15:02 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 12:15:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:15:02 INFO - 'USER': 'cltbld', 12:15:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:15:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:15:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:15:02 INFO - 'XPC_FLAGS': '0x0', 12:15:02 INFO - 'XPC_SERVICE_NAME': '0', 12:15:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:15:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:15:02 INFO - Downloading/unpacking jsonschema==2.5.1 12:15:02 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 12:15:02 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 12:15:02 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 12:15:02 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 12:15:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:15:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:15:03 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 12:15:03 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 12:15:03 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 12:15:03 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 12:15:03 INFO - Downloading functools32-3.2.3-2.tar.gz 12:15:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:15:04 INFO - warning: no files found matching '*.txt' 12:15:04 INFO - no previously-included directories found matching 'build' 12:15:04 INFO - no previously-included directories found matching 'dist' 12:15:04 INFO - no previously-included directories found matching '.git*' 12:15:04 INFO - Installing collected packages: jsonschema, functools32 12:15:04 INFO - Running setup.py install for functools32 12:15:04 INFO - warning: no files found matching '*.txt' 12:15:04 INFO - no previously-included directories found matching 'build' 12:15:04 INFO - no previously-included directories found matching 'dist' 12:15:04 INFO - no previously-included directories found matching '.git*' 12:15:04 INFO - Successfully installed jsonschema functools32 12:15:04 INFO - Cleaning up... 12:15:04 INFO - Return code: 0 12:15:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:15: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')]} 12:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:15: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')]} 12:15:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:15:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107c2ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6904a2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a08d30>, '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 0x10792fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079fdbe8>, '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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 12:15:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:15:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNSL9wiSPK/Render', 12:15:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:15:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:15:04 INFO - 'HOME': '/Users/cltbld', 12:15:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:15:04 INFO - 'LOGNAME': 'cltbld', 12:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:15:04 INFO - 'MOZ_NO_REMOTE': '1', 12:15:04 INFO - 'NO_EM_RESTART': '1', 12:15:04 INFO - 'PAGER': '/bin/cat', 12:15:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:15:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:15:04 INFO - 'PWD': '/builds/slave/test', 12:15:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:15:04 INFO - 'SHELL': '/bin/bash', 12:15:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUCi4VAi7q/Listeners', 12:15:04 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 12:15:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:15:04 INFO - 'USER': 'cltbld', 12:15:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:15:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:15:04 INFO - 'XPC_FLAGS': '0x0', 12:15:04 INFO - 'XPC_SERVICE_NAME': '0', 12:15:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:15:04 INFO - Cleaning up... 12:15:04 INFO - Return code: 0 12:15:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:15: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')]} 12:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:15: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')]} 12:15:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:15:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107c2ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6904a2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a08d30>, '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 0x10792fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079fdbe8>, '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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 12:15:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:15:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNSL9wiSPK/Render', 12:15:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:15:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:15:04 INFO - 'HOME': '/Users/cltbld', 12:15:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:15:04 INFO - 'LOGNAME': 'cltbld', 12:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:15:04 INFO - 'MOZ_NO_REMOTE': '1', 12:15:04 INFO - 'NO_EM_RESTART': '1', 12:15:04 INFO - 'PAGER': '/bin/cat', 12:15:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:15:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:15:04 INFO - 'PWD': '/builds/slave/test', 12:15:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:15:04 INFO - 'SHELL': '/bin/bash', 12:15:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUCi4VAi7q/Listeners', 12:15:04 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 12:15:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:15:04 INFO - 'USER': 'cltbld', 12:15:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:15:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:15:04 INFO - 'XPC_FLAGS': '0x0', 12:15:04 INFO - 'XPC_SERVICE_NAME': '0', 12:15:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:15:04 INFO - Downloading/unpacking blobuploader==1.2.4 12:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:15:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:15:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:15:06 INFO - Downloading blobuploader-1.2.4.tar.gz 12:15:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:15:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:15:06 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 12:15:06 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 12:15:06 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 12:15:06 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 12:15:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:15:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:15:06 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 12:15:06 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 12:15:06 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 12:15:06 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 12:15:07 INFO - Downloading docopt-0.6.1.tar.gz 12:15:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:15:07 INFO - Installing collected packages: blobuploader, requests, docopt 12:15:07 INFO - Running setup.py install for blobuploader 12:15:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:15:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:15:07 INFO - Running setup.py install for requests 12:15:07 INFO - Running setup.py install for docopt 12:15:07 INFO - Successfully installed blobuploader requests docopt 12:15:07 INFO - Cleaning up... 12:15:08 INFO - Return code: 0 12:15:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:15:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:15:08 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 0x107c2ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6904a2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a08d30>, '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 0x10792fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079fdbe8>, '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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 12:15:08 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 12:15:08 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 12:15:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNSL9wiSPK/Render', 12:15:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:15:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:15:08 INFO - 'HOME': '/Users/cltbld', 12:15:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:15:08 INFO - 'LOGNAME': 'cltbld', 12:15:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:15:08 INFO - 'MOZ_NO_REMOTE': '1', 12:15:08 INFO - 'NO_EM_RESTART': '1', 12:15:08 INFO - 'PAGER': '/bin/cat', 12:15:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:15:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:15:08 INFO - 'PWD': '/builds/slave/test', 12:15:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:15:08 INFO - 'SHELL': '/bin/bash', 12:15:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUCi4VAi7q/Listeners', 12:15:08 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 12:15:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:15:08 INFO - 'USER': 'cltbld', 12:15:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:15:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:15:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:15:08 INFO - 'XPC_FLAGS': '0x0', 12:15:08 INFO - 'XPC_SERVICE_NAME': '0', 12:15:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:15:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:15:09 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-P3i2VE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:15:09 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-WQUkjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:15:09 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-K2Py7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:15:09 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-vP7Xgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:15:09 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-HQ2Viw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:15:09 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-uE7a9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:15:09 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-UkV0uu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:15:09 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-tsQje4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:15:09 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Mi0fzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:15:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-BSqEqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:15:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-CwEQkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:15:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-jVWZ5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:15:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-POo6GT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:15:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-qRiPJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:15:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-FC6Ge6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:15:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-vMfR_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:15:10 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-TgMa5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:15:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:15:10 INFO - Running setup.py install for manifestparser 12:15:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:15:11 INFO - Running setup.py install for mozcrash 12:15:11 INFO - Running setup.py install for mozdebug 12:15:11 INFO - Running setup.py install for mozdevice 12:15:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:15:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:15:11 INFO - Running setup.py install for mozfile 12:15:11 INFO - Running setup.py install for mozhttpd 12:15:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:15:11 INFO - Running setup.py install for mozinfo 12:15:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:15:11 INFO - Running setup.py install for mozInstall 12:15:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:15:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:15:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:15:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:15:12 INFO - Running setup.py install for mozleak 12:15:12 INFO - Running setup.py install for mozlog 12:15:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:15:12 INFO - Running setup.py install for moznetwork 12:15:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:15:12 INFO - Running setup.py install for mozprocess 12:15:12 INFO - Running setup.py install for mozprofile 12:15:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:15:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:15:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:15:12 INFO - Running setup.py install for mozrunner 12:15:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:15:13 INFO - Running setup.py install for mozscreenshot 12:15:13 INFO - Running setup.py install for moztest 12:15:13 INFO - Running setup.py install for mozversion 12:15:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:15:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:15:13 INFO - Cleaning up... 12:15:13 INFO - Return code: 0 12:15:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:15:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:15:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:15:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:15:13 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 0x107c2ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6904a2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a08d30>, '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 0x10792fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079fdbe8>, '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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 12:15:13 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 12:15:13 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 12:15:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNSL9wiSPK/Render', 12:15:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:15:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:15:13 INFO - 'HOME': '/Users/cltbld', 12:15:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:15:13 INFO - 'LOGNAME': 'cltbld', 12:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:15:13 INFO - 'MOZ_NO_REMOTE': '1', 12:15:13 INFO - 'NO_EM_RESTART': '1', 12:15:13 INFO - 'PAGER': '/bin/cat', 12:15:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:15:13 INFO - 'PWD': '/builds/slave/test', 12:15:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:15:13 INFO - 'SHELL': '/bin/bash', 12:15:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUCi4VAi7q/Listeners', 12:15:13 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 12:15:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:15:13 INFO - 'USER': 'cltbld', 12:15:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:15:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:15:13 INFO - 'XPC_FLAGS': '0x0', 12:15:13 INFO - 'XPC_SERVICE_NAME': '0', 12:15:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:15:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:15:14 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-_sryX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:15:14 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)) 12:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:15:14 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Q6ggbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:15:14 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)) 12:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:15:14 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Ax12Pt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:15:14 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)) 12:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:15:14 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-yonp8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:15:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 12:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:15:14 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-mo8mTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:15:14 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)) 12:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:15:14 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-m72yPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:15:14 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)) 12:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:15:14 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-kH2ZXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:15:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:15:14 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-F2QzYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:15:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:15:14 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-_CBtb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:15:15 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)) 12:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:15:15 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-nT2MA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:15:15 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)) 12:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:15:15 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-idspzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:15:15 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)) 12:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:15:15 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-7YrUSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:15:15 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)) 12:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:15:15 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Wdzju6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:15:15 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)) 12:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:15:15 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-A1mADa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:15:15 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)) 12:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:15:15 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-bMVowF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:15:15 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)) 12:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:15:15 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-c9_u_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:15:15 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)) 12:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:15:15 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-96beHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:15:15 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)) 12:15:15 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)) 12:15:15 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)) 12:15:15 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)) 12:15:15 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:15:15 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:15:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:15:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:15:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:15:17 INFO - Downloading blessings-1.6.tar.gz 12:15:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:15:17 INFO - Installing collected packages: blessings 12:15:17 INFO - Running setup.py install for blessings 12:15:17 INFO - Successfully installed blessings 12:15:17 INFO - Cleaning up... 12:15:17 INFO - Return code: 0 12:15:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:15:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:15:17 INFO - Reading from file tmpfile_stdout 12:15:17 INFO - Current package versions: 12:15:17 INFO - blessings == 1.6 12:15:17 INFO - blobuploader == 1.2.4 12:15:17 INFO - docopt == 0.6.1 12:15:17 INFO - functools32 == 3.2.3-2 12:15:17 INFO - jsonschema == 2.5.1 12:15:17 INFO - manifestparser == 1.1 12:15:17 INFO - mozInstall == 1.12 12:15:17 INFO - mozcrash == 1.0 12:15:17 INFO - mozdebug == 0.1 12:15:17 INFO - mozdevice == 0.49 12:15:17 INFO - mozfile == 1.2 12:15:17 INFO - mozhttpd == 0.7 12:15:17 INFO - mozinfo == 0.9 12:15:17 INFO - mozleak == 0.1 12:15:17 INFO - mozlog == 3.4 12:15:17 INFO - moznetwork == 0.27 12:15:17 INFO - mozprocess == 0.25 12:15:17 INFO - mozprofile == 0.28 12:15:17 INFO - mozrunner == 6.13 12:15:17 INFO - mozscreenshot == 0.1 12:15:17 INFO - mozsystemmonitor == 0.3 12:15:17 INFO - moztest == 0.8 12:15:17 INFO - mozversion == 1.4 12:15:17 INFO - psutil == 3.1.1 12:15:17 INFO - requests == 1.2.3 12:15:17 INFO - wsgiref == 0.1.2 12:15:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:15:17 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')]} 12:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:15:17 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')]} 12:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:15:17 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 0x107c2ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6904a2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a08d30>, '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 0x10792fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079fdbe8>, '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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 12:15:17 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 12:15:17 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 12:15:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNSL9wiSPK/Render', 12:15:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:15:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:15:17 INFO - 'HOME': '/Users/cltbld', 12:15:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:15:17 INFO - 'LOGNAME': 'cltbld', 12:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:15:17 INFO - 'MOZ_NO_REMOTE': '1', 12:15:17 INFO - 'NO_EM_RESTART': '1', 12:15:17 INFO - 'PAGER': '/bin/cat', 12:15:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:15:17 INFO - 'PWD': '/builds/slave/test', 12:15:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:15:17 INFO - 'SHELL': '/bin/bash', 12:15:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUCi4VAi7q/Listeners', 12:15:17 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 12:15:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:15:17 INFO - 'USER': 'cltbld', 12:15:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:15:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:15:17 INFO - 'XPC_FLAGS': '0x0', 12:15:17 INFO - 'XPC_SERVICE_NAME': '0', 12:15:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:15:18 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)) 12:15:18 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)) 12:15:18 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)) 12:15:18 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)) 12:15:18 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)) 12:15:18 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)) 12:15:18 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)) 12:15:18 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)) 12:15:18 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)) 12:15:18 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)) 12:15:18 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)) 12:15:18 INFO - Cleaning up... 12:15:18 INFO - Return code: 0 12:15:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:15: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')]} 12:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:15: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')]} 12:15:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:15:18 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 0x107c2ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c2d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6904a2e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a08d30>, '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 0x10792fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079fdbe8>, '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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 12:15:18 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 12:15:18 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 12:15:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNSL9wiSPK/Render', 12:15:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:15:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:15:18 INFO - 'HOME': '/Users/cltbld', 12:15:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:15:18 INFO - 'LOGNAME': 'cltbld', 12:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:15:18 INFO - 'MOZ_NO_REMOTE': '1', 12:15:18 INFO - 'NO_EM_RESTART': '1', 12:15:18 INFO - 'PAGER': '/bin/cat', 12:15:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:15:18 INFO - 'PWD': '/builds/slave/test', 12:15:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:15:18 INFO - 'SHELL': '/bin/bash', 12:15:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUCi4VAi7q/Listeners', 12:15:18 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 12:15:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:15:18 INFO - 'USER': 'cltbld', 12:15:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:15:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:15:18 INFO - 'XPC_FLAGS': '0x0', 12:15:18 INFO - 'XPC_SERVICE_NAME': '0', 12:15:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:15:18 INFO - Cleaning up... 12:15:18 INFO - Return code: 0 12:15:18 INFO - Running post-action listener: _resource_record_post_action 12:15:18 INFO - Running post-action listener: _start_resource_monitoring 12:15:18 INFO - Starting resource monitoring. 12:15:18 INFO - [mozharness: 2017-05-18 19:15:18.528549Z] Finished create-virtualenv step (success) 12:15:18 INFO - [mozharness: 2017-05-18 19:15:18.529480Z] Running install step. 12:15:18 INFO - Running pre-action listener: _resource_record_pre_action 12:15:18 INFO - Running main action method: install 12:15:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:15:18 INFO - Reading from file tmpfile_stdout 12:15:18 INFO - Detecting whether we're running mozinstall >=1.0... 12:15:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:15:18 INFO - Reading from file tmpfile_stdout 12:15:18 INFO - Output received: 12:15:18 INFO - Usage: mozinstall [options] installer 12:15:18 INFO - Options: 12:15:18 INFO - -h, --help show this help message and exit 12:15:18 INFO - -d DEST, --destination=DEST 12:15:18 INFO - Directory to install application into. [default: 12:15:18 INFO - "/builds/slave/test"] 12:15:18 INFO - --app=APP Application being installed. [default: firefox] 12:15:18 INFO - mkdir: /builds/slave/test/build/application 12:15:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:15:33 INFO - Reading from file tmpfile_stdout 12:15:33 INFO - Output received: 12:15:33 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:15:33 INFO - Running post-action listener: _resource_record_post_action 12:15:33 INFO - [mozharness: 2017-05-18 19:15:33.718952Z] Finished install step (success) 12:15:33 INFO - [mozharness: 2017-05-18 19:15:33.719083Z] Running run-tests step. 12:15:33 INFO - Running pre-action listener: _resource_record_pre_action 12:15:33 INFO - Running main action method: run_tests 12:15:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:15:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:15:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:15:33 INFO - 2017-05-18 12:15:33.733 screenresolution[1618:7423] starting screenresolution argv=screenresolution get 12:15:33 INFO - 2017-05-18 12:15:33.736 screenresolution[1618:7423] Display 0: 1600x1200x32@60 12:15:33 INFO - 2017-05-18 12:15:33.742 screenresolution[1619:7425] starting screenresolution argv=screenresolution list 12:15:33 INFO - Available Modes on Display 0 12:15:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:15:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:15:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:15:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:15:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:15:33 INFO - Intel Iris: 12:15:33 INFO - Chipset Model: Intel Iris 12:15:33 INFO - Type: GPU 12:15:33 INFO - Bus: Built-In 12:15:33 INFO - VRAM (Dynamic, Max): 1536 MB 12:15:33 INFO - Vendor: Intel (0x8086) 12:15:33 INFO - Device ID: 0x0a2e 12:15:33 INFO - Revision ID: 0x0009 12:15:33 INFO - Displays: 12:15:33 INFO - Display: 12:15:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:15:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:15:33 INFO - Display Serial Number: 1600x1200 60 12:15:33 INFO - Main Display: Yes 12:15:33 INFO - Mirror: Off 12:15:33 INFO - Online: Yes 12:15:33 INFO - Rotation: Supported 12:15:33 INFO - Return code: 0 12:15:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:15:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:15:33 INFO - Python 2.7.3 12:15:33 INFO - Return code: 0 12:15:33 INFO - grabbing minidump binary from tooltool 12:15:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:15:33 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a08d30>, '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 0x10792fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079fdbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:15:33 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:15:33 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:15:33 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:15:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:15:34 INFO - Return code: 0 12:15:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:15:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:15:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:15:34 INFO - ENV: RUST_BACKTRACE is now 1 12:15:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:15:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:15:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0161', '--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 12:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0161 --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 12:15:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bNSL9wiSPK/Render', 12:15:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:15:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:15:34 INFO - 'HOME': '/Users/cltbld', 12:15:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:15:34 INFO - 'LOGNAME': 'cltbld', 12:15:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:15:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:15:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:15:34 INFO - 'MOZ_NO_REMOTE': '1', 12:15:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:15:34 INFO - 'NO_EM_RESTART': '1', 12:15:34 INFO - 'PAGER': '/bin/cat', 12:15:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:15:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:15:34 INFO - 'PWD': '/builds/slave/test', 12:15:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:15:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:15:34 INFO - 'RUST_BACKTRACE': '1', 12:15:34 INFO - 'SHELL': '/bin/bash', 12:15:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KUCi4VAi7q/Listeners', 12:15:34 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 12:15:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:15:34 INFO - 'USER': 'cltbld', 12:15:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:15:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:15:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:15:34 INFO - 'XPC_FLAGS': '0x0', 12:15:34 INFO - 'XPC_SERVICE_NAME': '0', 12:15:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:15:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0161', '--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 12:15:34 INFO - mozversion application_buildid: 20170518105722 12:15:34 INFO - mozversion application_changeset: 54f218cec92ffcc2cc8160ed80c58ffe8b6c268d 12:15:34 INFO - mozversion application_display_name: Firefox 12:15:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:15:34 INFO - mozversion application_name: Firefox 12:15:34 INFO - mozversion application_remotingname: firefox 12:15:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:15:34 INFO - mozversion application_vendor: Mozilla 12:15:34 INFO - mozversion application_version: 54.0 12:15:34 INFO - mozversion platform_buildid: 20170518105722 12:15:34 INFO - mozversion platform_changeset: 54f218cec92ffcc2cc8160ed80c58ffe8b6c268d 12:15:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:15:34 INFO - mozversion platform_version: 54.0 12:15:34 INFO - using testdate: 1495134934 12:15:34 INFO - actual date: 1495134934 12:15:34 INFO - starting webserver on 'localhost:49223' 12:15:34 INFO - SUITE-START | Running 2 tests 12:15:34 INFO - TEST-START | dromaeo_css 12:15:34 INFO - operating with platform_type : mac_ 12:15:34 INFO - Initialising browser for dromaeo_css test... 12:15:34 INFO - TEST-INFO | started process 1629 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpggAIjf/profile http://localhost:49223/getInfo.html) 12:15:37 INFO - PROCESS | 1629 | 2017-05-18 12:15:37.112 plugin-container[1632:7591] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4f, name = 'com.apple.tsm.portname' 12:15:37 INFO - PROCESS | 1629 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:15:37 INFO - PROCESS | 1629 | 2017-05-18 12:15:37.113 plugin-container[1632:7591] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f17, name = 'com.apple.CFPasteboardClient' 12:15:37 INFO - PROCESS | 1629 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:15:37 INFO - PROCESS | 1629 | 2017-05-18 12:15:37.113 plugin-container[1632:7591] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:15:37 INFO - PROCESS | 1629 | __metrics Screen width/height:1600/1200 12:15:37 INFO - PROCESS | 1629 | colorDepth:24 12:15:37 INFO - PROCESS | 1629 | Browser inner width/height: 1024/689 12:15:37 INFO - PROCESS | 1629 | __metrics 12:15:37 ERROR - PROCESS | 1629 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 12:15:37 INFO - TEST-INFO | 1629: exit 0 12:15:37 INFO - Browser initialized. 12:15:37 INFO - Running cycle 1/1 for dromaeo_css test... 12:15:37 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpggAIjf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:15:40 INFO - PROCESS | 1633 | 2017-05-18 12:15:40.129 plugin-container[1634:7722] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4243, name = 'com.apple.tsm.portname' 12:15:40 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:15:40 INFO - PROCESS | 1633 | 2017-05-18 12:15:40.130 plugin-container[1634:7722] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 12:15:40 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:15:40 INFO - PROCESS | 1633 | 2017-05-18 12:15:40.130 plugin-container[1634:7722] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:15:49 INFO - PROCESS | 1633 | RSS: Main: 173940736 12:15:49 INFO - PROCESS | 1633 | 12:18:53 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49223/tests/dromaeo/cssquery-ext.html) 12:18:54 INFO - PROCESS | 1633 | RSS: Main: 193531904 12:18:54 INFO - PROCESS | 1633 | 12:21:57 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-ext.html (next: http://localhost:49223/tests/dromaeo/cssquery-jquery.html) 12:21:57 INFO - PROCESS | 1633 | RSS: Main: 201007104 12:21:57 INFO - PROCESS | 1633 | 12:24:59 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49223/tests/dromaeo/cssquery-mootools.html) 12:25:00 INFO - PROCESS | 1633 | RSS: Main: 204578816 12:25:00 INFO - PROCESS | 1633 | 12:28:02 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49223/tests/dromaeo/cssquery-prototype.html) 12:28:02 INFO - PROCESS | 1633 | RSS: Main: 208392192 12:28:02 INFO - PROCESS | 1633 | 12:31:05 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49223/tests/dromaeo/cssquery-yui.html) 12:31:06 INFO - PROCESS | 1633 | RSS: Main: 210866176 12:31:06 INFO - PROCESS | 1633 | 12:34:09 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-yui.html (next: http://localhost:49223/tests/dromaeo/cssquery-dojo.html) 12:34:09 INFO - PROCESS | 1633 | RSS: Main: 208863232 12:34:09 INFO - PROCESS | 1633 | 12:34:09 INFO - PROCESS | 1633 | __start_tp_report 12:34:09 INFO - PROCESS | 1633 | _x_x_mozilla_page_load 12:34:09 INFO - PROCESS | 1633 | _x_x_mozilla_page_load_details 12:34:09 INFO - PROCESS | 1633 | |i|pagename|runs| 12:34:09 INFO - PROCESS | 1633 | |0;dojo.html;3611.4402267648506;3622.728295377847;3624.601293857675;3641.4173732202844;3645.343838109141;5968.806238752245;6231.252249730033;6232.844178895524;6254.43710066094;6263.185785847839;5383.192521121834;6212.1303017577575;6234.84412889678;6257.280412752535;6263.749450021994;4230.555791641879;5258.1849813279005;5658.179563963223;5699.344575373823;5759.712014399284;5732.48407643312;5753.424657534234;5761.884762304751;5761.897195519171;5769.336526299488;4611.377464042362;5525.861853453655;5543.77824887004;5581.944180558183;5727.312722473309;5646.745896434671;5700.971495142539;5701.2018317435795;5757.769689212427;5764.654120752726;5677.290338707661;5682.374938756733;5744.368119506851;5750.453706897838;5756.33802112754;6233.470155036782;6273.12176295319;6283.654399008053;6306.590071645374;6320.241571011508;5313.840584782462;6300.086487459339;6363.109164716943;6509;6551.4431273341825;6745.426638735713;6747.831304217385;6751.358620931003;6753.3921947025;6759.797206083826;6311.274203466568;6322.557420980487;6340.3342649022115;6355.142055822497;6373.904391434132;6559.6064236145985;6632.369924857131;6683.532152749358;6773.424258937947;6839.213490448563;6501.414872661483;6554.377334153248;6566.770163044271;6574.243961944438;6576.309487503743;5530.751116199781;6684.9331506685285;6689.5986240825705;6693.799186024427;6709.865802684017;5200.791968321225;6331.651759153198;6546.214454265423;6637.834054148589;6660.034295027194;6528.281888992207;6572.638504882278;6590.077389165521;6596.472282217411;6598.670066496656;4378.365137055109;5235.764390602432;5311.123664595299;5358.91961620576;5362.570994320445;4778;4794.880127996758;4802.639802014863;4839.7096174229655;4857.878553036132;6049.606775559574;6351.396617321347;6361.459275961595;6376.266729326094;6385.680715964183;6579.3420657934785;6585.111010013587;6588.4729221662155;6602.900956485657;6742.224644165885;6307.369263073656;6308.716107775161;6317.6209427434505;6319.778807741707;6330.873382532323;5908.734106965197;5919.644821310735;5932;5936.309281017152;6002.459778619946;6640.302768209269;6690.866182676416;6819.965900170468;6827.146606674166;6882.346177113166;6683.799486015428;6689.832754181184;6735.0570920072205;6747.696353663999;6753.425958793558;6921.204061532787;6929.89605155913;6953.860922781617;6963.4777391694815;6967.233604303421;6332;6405.807825765234;6550.508711846535;6619.139511863427;6638.767643132467;5776;6278;6677.399034086761;6744.123263975652;6753.864922701617;6488.188976377953;6521.282658907611;6538.346165383424;6539.280679125388;6543.116679248241;6267.373262673878;6313.873722525432;6409.9038514423255;6473.352664733489;6548.345165483413;6316.936830631818;6340.461060809698;6410.391012853772;6455.870882582416;6459.063435801689;6532.085508028975;6539.215294164731;6561.737530498726;6563.967180164069;6576.177977752781;6238.064290356483;6253.1245625613365;6289.371062893674;6340.175777148941;6365.04524321337 12:34:09 INFO - PROCESS | 1633 | |1;ext.html;773.5204173412482;832.2260297922932;851.1956201389685;995.1541189988507;997.0926456924201;39712.80143599281;40661;42734.14531709364;42904.9273768156;42960.35559466603;31502.684973150263;41187;41369.37945930813;41429.549965751205;41445.792771036104;8298.751037468886;8408.915910840906;11324.32054076754;11569.074474042098;11798.94100529496;11445.885541144566;11575.53697852085;11586.768264634702;11587.594434194805;11631.069514438866;9483.193928516084;11504.194706370557;11537.769244615145;11580.71048223797;11583.652490425258;11433.942830285838;11511.481983310729;11529.135314851335;11539.711507212303;11569.13231507636;11227.326360418401;11261.943690281496;11280.379579123144;11298.774024519545;11334.773304533946;29837.552436713468;34164.975050748515;35810.64189358099;35985.64014359875;36050.73822416229;3945.230680017398;3959.821808018617;3973.384125460558;3985.183037477339;4021.356582946744;26699.065532706456;27616;27702.722972770214;27706;27729;3174.460341741886;3226.4192445359827;3233.676632336771;3312.751543634237;3425.2293234022395;380046;381388;382014;382161;383458;46037;46462;46561.5343846564;46687.29969050467;46700.065998679835;48130.51869481261;48472.51527484751;48606.51393486091;48648;48719.75640121777;14649.633759155893;14785.704285914222;14841.812654987574;14849.628759280888;14856.851431485762;12501.687457813627;12570.811437828439;12727.918126959126;12759.61721148367;12811.551595694105;10981;12377.690557736129;12382.504699812087;12407.317597532223;12487.93756031214;4973.731698416953;5847.064469684915;5855.3851845556455;5866.911996320058;5875.353711091862;1141.8401423800674;1176.5705517486226;1179.121554441929;1185.7805808534292;1211.9939400302942;28371.432571348458;28567.42879141667;28883.56674649882;29014.709852901622;29023.41953160925;1265.373640048182;1267.5436842736608;1300.3303298801125;1304.9804252936215;1324.0929962975392;10541.631042913614;11190.160737944687;14323.283835808168;14503.274836258144;14894.478693245685;203099;203923;204270;204652;204928;58040.4195958035;58393.70803145957;58618.827623447294;58966;59144.70427647834;1103.4372470040178;1316.973660526784;1502.9924850375678;1503.9849601504063;1510.9924450377898;26569.67151642449;30209.848950755106;31523.21191970219;31946.36107277888;32246.871359503242;41335;43696.68909932707;44292.55707442884;44439.77780111079;44468.777656112805;445140;446477;446926;447210;447438;32650.51024234686;33158.33683326368;33173.33653326872;33399.666003339655;33423.830165945044;45169;45987;46829.76585117052;47218.29172562483;47250.76374618105;31296.530552042193;31392.215194620316;31647.84176079105;32659.510107347935;32870.178245544055;31289.217769555944;31459;31562.579683914737;31791.52312715265;32224.516632251005;36697.899063028155;37339.69311074208;37490.81254593709;37519;37525;30861.38277234398;31642.841785790923;32067.518987214742;32665.836670816494;32800;22317.553648926605;23005.88497057504;24279.393015174057;24639.876800615883;24713.629295560942 12:34:09 INFO - PROCESS | 1633 | |2;jquery.html;419.2662840029948;428.4430240687106;433.05593805504;437.78248967979187;442.8870637987315;20777;21212.46968825778;22309.665355019686;22513.212037578687;22640.66039009414;18548.721769173473;21975.670364944537;22300.442488937744;22894.77105228947;23147.958341874615;6832.760853370109;6842.555233909792;7120.003199552041;7174.677139528734;7187.964060179692;6951.513394062417;7101.680424380889;7160.64196790161;7163.31948464895;7171.856562868739;7091.361777440029;7144.607046612434;7565.13001004886;7960.681572737112;8069.838603227932;6372.776952806676;7934.841303173933;7953.880691789628;8013.639386227634;8023.077346105185;7722.575258360788;7733.41999350051;7777.688892444296;7781.922180778233;7820.687172513092;25118;25958;26025.73974260252;26640;26946.595801063;2410.3853517353136;2960.141558947924;2969.376430949503;3066.693330666938;3155.794873333249;15027.624309392353;15734.842651573452;16034.518964431085;16231.513054608262;16712;1838.347386677737;2053.3839848045677;2108.620448319302;2121.586290673319;2147.7530084040222;152116;154920;160144.39855601412;160992;163218;17222.741658875126;20413.67311124772;20903.68644470334;21266.574668506542;21465.46336341604;32602.673973260436;32664.183395414828;33236.50145247823;33340.66659333376;33380.332393352;10484.10885074762;10708.78582428347;10886.183536234814;10931.945340273407;11015.394153321484;9041.954790226007;9046.502442365732;9057.77355566103;9114.58984345706;9187;7730.96134519335;9447.480388578557;9679.370840895319;9720.465374404475;9725.513724313756;3420.863165473403;4009.4186342980106;4071.491063617048;4081.7550347309457;4099.959000409957;1038.325088692356;1042.8957104289511;1070.662741236508;1079.5897558927713;1096.2107282756403;19692.60614787725;20387.082581283878;21707.674384884238;21828.781712182994;21965.26599461005;920.40173886974;999.2006394884064;1079.1474734959452;1103.4937310571013;1132.4847194526471;10105.343152695052;10370.481475926174;11195.944020280009;11271.38007409584;11405.942970285096;579739;663825;782517;790947.0905290989;801824;125133.74866251221;151587.24206379117;167500;177051;180719;1314.086709736743;1327.1108357400565;1365.9317034148253;1370.225822410335;1370.2532119994628;18808.905955470134;18957.43127706171;19957.900210498854;19982.400527984184;19989;57781.711091444275;60021.39978600333;64380;68910.62178756497;69065.65467172833;96168;96910.51544742231;97206;97499.53750693603;99704;10953.34279943206;11510.884891151316;11745.29528228309;11906.464209110783;12038.337891416057;12739.108262421541;12797.168184068007;12845.871541284467;12963.546275880299;13107.016973727199;11090.611828585961;11163.88836111661;11291.031945943758;11317.490712917774;11341.716457088638;10313.793724125326;10518.579256829948;10588.576456941637;10689.30519516229;10908.127349811837;11337.546498139982;11428.599998999995;11469.942650286695;11495.8275625864;11769.117316201491;11542.84571542839;11659.53361865516;11680.94159529197;11685.824712629488;11939.223950443193;10817.891821081688;11176.664700059011;11204.43977801123;11391.658250252505;11428.25716328436 12:34:09 INFO - PROCESS | 1633 | |3;mootools.html;1450.216882883243;1538.1078974194966;1613.540141059798;1648.7526870969361;1663.4593757028965;11223.270487418313;11612.477438515267;11974.982126519246;12001.459934302966;12021.398930053485;11604.709882252948;11777.70555736107;11829.40852957353;11977.520899164023;12001.399930003487;2398.784109430151;2449.9265022049276;2454.754524547549;2455.4475243070347;2455.6807615010025;2448.5198213509325;2462.322861213163;2508.147229941819;2512.472380800034;2516.144510866305;2431.914882979096;2464.8151388645833;2472.9010839566395;2475.356407334097;2478.913238036669;2430.1251549442186;2460.2865169100937;2471.5798314286612;2473.245660073677;2478.293686299414;2413.8189635777385;2438.975610243893;2442.6213936839818;2448.1309135257084;2453.4479742058074;11483.655490335303;11668.533258669642;11751.001164900994;11752.294862308288;11756.706082347924;5873.911891321633;6021.939780602182;6025.0661147522205;6025.307089684698;6034.879302413927;2867.899623513188;3613.728970327236;3665.5234819473562;3666.4866918631415;3674.761140525857;1257.4215860704087;1264.576366917084;1290.9935450322782;1312.343828085957;1314.8027795830606;14276.500322488768;14542.272886355746;15174.848251517455;15943.362265509366;16057.357705691758;4680.695754775997;4774.283857421345;4807.831725889647;4813.253945638432;4821.349117869043;12078.637640870787;12102.81845772314;12175.878241217475;12178.512859485698;12233;5818.631696152419;5844.678542680108;5881.823545293648;5944.940550594525;5951.315598706205;5304.9920515102;5371.4897084776885;5432;5435.10320797064;5443.646162999472;3805.040404191843;3973.304671682444;3977.1846771411792;3982;4051.7974101294817;1045.7542477517777;1049.1711547877244;1062.9574817007233;1064.180580952662;1065.0680654427376;819.6598411659214;935.4340623922565;945.3807755919884;946.4321407155652;970.8883478399933;5769.88460230793;6621.370969757865;6723.966380168068;6808.523403361816;6879.552829066095;1001.33911618333;1015.5176291261591;1019.6846068571459;1041.1514615588271;1041.5052849896238;4171.186618609342;4179.310413781692;4182.205380977604;4189.685773566995;4327.394164816928;12644.683882902817;13191;13223.669408264677;13240.337983100999;13252.602421927357;13072.019598530149;13127.737445251232;13142.4085916134;13180.472781088842;13205.801912971314;8366.828643989724;9849.45827979468;10199.28604997658;10365.585376584851;10373.740656483498;6797.218319893275;6938.098047253825;7009.649517524002;7103.431725462054;7155.177154627077;10166;11814.700382958039;12334.444949977093;12637.304948227937;12716.364181790688;11803.937645611935;12075.879241207827;12761.170523916287;13241.602751917459;13261.469541218243;9273.53632318395;9544.42733435996;9606.327557070937;9632.27757918145;9636.614535418505;10272.280940334105;10574.84137737919;10614.89385106139;10663;10797.62208322705;9077.591508382007;9179.632814687338;9202.217811486098;9341.34610577253;9364.250859931053;8937.374383793333;8998.775030624287;9009.0990900912;9058.275337972815;9188.264938804747;8179.877301840358;8182.427230094075;8264.421490495608;8323.750287491384;8333;5930.436608522184;5971.641701497924;5989.940100598938;6114.969425152845;6232.501399888089;5979.820605381846;6024.668643224665;6198.194234749454;6255.530835187288;6313.179286692701 12:34:09 INFO - PROCESS | 1633 | |4;prototype.html;393.9980300098499;398.9481367422235;400.7435241445475;401.3578274760384;403.22983102274645;5360.517553420192;5379.865503362418;5489.533389661879;5495.505404513598;5496.615236933406;4259.744415335082;4308.104434049016;4839.975800120995;5183.326167598207;5258.92111618326;3692.7230457715646;3858.8070596470207;4427.343593843684;4449.933251001237;4466.79899404526;3963.454252841394;4276.6792490563175;4490.281554951209;4494.573015563533;4528.1698938163645;3998.900027499307;4171.749695018309;4369.924285964174;4494.617957473619;4502.752348620825;3790.8673196438267;3878.9224215515815;4253.595908388698;4273.247968332984;4402.713823601455;3307.684620765474;3403.506491558735;3679.3929001714687;4140.772257525835;4240.597143271384;4608.585227329523;4628.4445866496235;4668.043051174501;5207.60942929278;5237.371515418136;4362.8036738346855;4486.102779444124;4685.320628508881;4774.856754297377;5147.150720131174;5071.315372424712;5724.198612194296;5734.3657057738765;5748.7125643717645;5844.064949608042;3989.501312335958;4547.590716835501;4552.954470455286;4566.54334566544;4604.52655150352;4897.902041959176;5410.648307860016;5587.05020146576;5641.971790141064;5936.7328470218945;3917.7061720371084;4165.291900376883;4471.552844715502;4655.976720116378;4738.47876733559;4764.118638051971;4771.904561908812;4805.6155507559315;4808.711477311373;4902.7058376497525;4807.61539076873;4877.561019508261;4877.951220487821;5041;5123.334916152191;5640.251522028185;5674.574406919498;5695.914561281584;5705.286839145107;5713.8285851424525;1433.0470237292084;1609.4849648112727;1617.781599484078;1625.4392234679017;1627.5361521966183;1070.699100592768;1123.6067376418353;1128.2158899564888;1156.888678162357;1209.727811242481;5396.703181325065;5408.3509978802795;5422.945770542323;5423.511883930465;5434.809781657702;5316.680999140064;5670.8015219467125;5938.871614393252;5986;6016.278046634432;4662.081504013298;4783.051618582261;4814.566688997937;5197.245459906256;5416.187571864252;2777.402858385457;2790.3582176099203;3109.3470371222297;3127.5308703694172;3129.937401251962;5543.059929024889;5672.376038635747;5689.630174038757;6068.33248342682;6142.754289828446;5103.77033033507;5127.435982041972;5993.250843644544;6046.762697801842;6079.422454866781;5459.918101228484;5735.713214339266;6096.878062438726;6103.694815259308;6161.876762464725;5281.781651706369;5437.429069947599;5447.6769170806565;5880.235569375954;5946.583739138218;5450;5593.972030139823;5889.882202356014;5914.911276330772;5962.763963513682;5077.695338279715;5089.312942752681;5874.647521148574;5903.144044113494;6185.721642526187;3820.3887378019385;4134.635570261747;4658.440987081572;4674.392328997209;4695.859124226278;5543.9168412474655;5839.620424672383;5877.853053673693;5885.587459009893;5904.380040095728;4918.680285781413;4959.20652695567;5077.568406685473;5695.601307908407;5733.913991290217;5198.428172901054;5237.978594174099;5701.458361455655;5735.541156707537;5795.53689681892;5225.555827754683;5549.196511133979;5618.876224754984;5641.771500257458;5663.658441519523;4943.802247910044;5628.015097358028;5718.79984200551;5756.222909907109;5765.250517432707;5250.343707036621;5638.4925356718095;5650.943490565041;5668.574856885668;5691.8861622768145 12:34:09 INFO - PROCESS | 1633 | |5;yui.html;750.6697053296552;762.8550575390672;798.872180451128;866.9653213871445;872.6378552900546;15940.601484962866;17291.913540432313;17370.305187792488;17384.913075434604;17424;15972.361105555763;16081.919590402062;16106.919465402656;16121.113338766363;16135.677286454293;993.8310487217191;1003.4546797931196;1036.7097212780434;1038.5586125896502;1039.807635587415;1011.1102230037557;1024.4314405504979;1035.7824856780085;1037.035556932053;1040.266612038511;1062.5059347403455;1080.3409919948824;1080.346390433784;1081.0918828184324;1082.880883102858;1053.6470282455386;1073.8711354637494;1075.2580719303694;1079.3631757263213;1079.6545105566222;791.4064451661254;792.4928046050533;805.6173317674117;806.8951036365294;807.4872455990429;16899;17150.65698686032;17538.649227015387;18119.909400453045;18236.726449103273;14694;14707.852921470756;14877.925610371878;14878.376287244628;15061.548153555412;11806.46870890812;12159.419275494218;12257.14200006;12273.202241854251;12288.139830211889;3293.2425542125447;3824.349860523718;3858.3826587745825;3875.341191997339;3968.5832987536182;95673.52163239139;102493.97506024918;107487.46256268746;108870.01933464917;109846;9861.704148875546;11607.76784464306;11630.88369116298;11679;11727.706807329885;23163.420914477272;23429.94565244568;23468.765312347;23482.295531134092;23601.7639823603;6794.422474089659;8195.95902020486;8403.327733781283;8432.662693492191;8685.348598854986;4454.77726113693;4470.284754439274;4506.211413002711;4538.546145385501;4568.17772800899;3674.853005879735;3738.626137386294;3761.946654936622;3779.7354185206773;3806.410006449005;688.2532452289222;690.3027941778739;690.5131882023162;697.9197392299849;705.7388766156555;684.5071548485083;701.8314506346977;718.2781304788758;722.7903907866765;741.661281503202;10540.613420955497;10648.361098334124;10791.6222932197;10913.890861091446;11002.449877506253;658.4271683635268;699.7166147710185;705;714.6248219684708;721.4480922094601;5026.597872170218;5223.13818219997;5239.345081864767;5244.711540865289;5272.99813035522;69141.65429172822;83652.16347836444;98641;101328;104286;75298.11754706067;75426;76133;76811;77249;68038.99999999901;69822;78256;78334.608326958;78376;10256.589736410759;10934.179936504954;11231.058533236413;11375.829362559403;11521.366324851879;45324.32013519866;47299;49700.75149624229;50034.74913127201;53257.46742532525;62865.68567157135;64805.67597161984;65506.67246663736;66039;66635.33364666291;10833;13199.67000824987;13293.867061329263;13705.794413084011;13714.862851371758;14773.778393323893;15141.848581514485;15728.13495257726;15758.448454304043;16266.349346026027;14099.859001409855;14503.709925801635;14504.347304371118;14855.702885942437;14973.475928342892;13922.51271205503;14422.77406420424;14452.783208251674;14514.201718905138;15023.323950422038;13732.588022359345;14310.85689143137;14324.57026289213;14327.57017289483;14922.701545969237;9973.76373421227;10518.421486818299;10520.368777873353;10574.41840698769;10585.788284234119;8836.513991730517;9324.393914395814;11109.277896936997;11460.426978651201;11694 12:34:09 INFO - PROCESS | 1633 | __end_tp_report 12:34:09 INFO - PROCESS | 1633 | __start_cc_report 12:34:09 INFO - PROCESS | 1633 | _x_x_mozilla_cycle_collect,395 12:34:09 INFO - PROCESS | 1633 | __end_cc_report 12:34:09 INFO - PROCESS | 1633 | __startTimestamp1495136049682__endTimestamp 12:34:09 INFO - PROCESS | 1633 | 12:34:09 INFO - PROCESS | 1633 | ------- Summary: start ------- 12:34:09 INFO - PROCESS | 1633 | Number of tests: 6 12:34:09 INFO - PROCESS | 1633 | 12:34:09 INFO - PROCESS | 1633 | [#0] dojo.html Cycles:165 Average:6140.88 Median:6340.18 stddev:702.55 (11.1%) stddev-sans-first:676.11 12:34:09 INFO - PROCESS | 1633 | Values: 3611.4 3622.7 3624.6 3641.4 3645.3 5968.8 6231.3 6232.8 6254.4 6263.2 5383.2 6212.1 6234.8 6257.3 6263.7 4230.6 5258.2 5658.2 5699.3 5759.7 5732.5 5753.4 5761.9 5761.9 5769.3 4611.4 5525.9 5543.8 5581.9 5727.3 5646.7 5701.0 5701.2 5757.8 5764.7 5677.3 5682.4 5744.4 5750.5 5756.3 6233.5 6273.1 6283.7 6306.6 6320.2 5313.8 6300.1 6363.1 6509.0 6551.4 6745.4 6747.8 6751.4 6753.4 6759.8 6311.3 6322.6 6340.3 6355.1 6373.9 6559.6 6632.4 6683.5 6773.4 6839.2 6501.4 6554.4 6566.8 6574.2 6576.3 5530.8 6684.9 6689.6 6693.8 6709.9 5200.8 6331.7 6546.2 6637.8 6660.0 6528.3 6572.6 6590.1 6596.5 6598.7 4378.4 5235.8 5311.1 5358.9 5362.6 4778.0 4794.9 4802.6 4839.7 4857.9 6049.6 6351.4 6361.5 6376.3 6385.7 6579.3 6585.1 6588.5 6602.9 6742.2 6307.4 6308.7 6317.6 6319.8 6330.9 5908.7 5919.6 5932.0 5936.3 6002.5 6640.3 6690.9 6820.0 6827.1 6882.3 6683.8 6689.8 6735.1 6747.7 6753.4 6921.2 6929.9 6953.9 6963.5 6967.2 6332.0 6405.8 6550.5 6619.1 6638.8 5776.0 6278.0 6677.4 6744.1 6753.9 6488.2 6521.3 6538.3 6539.3 6543.1 6267.4 6313.9 6409.9 6473.4 6548.3 6316.9 6340.5 6410.4 6455.9 6459.1 6532.1 6539.2 6561.7 6564.0 6576.2 6238.1 6253.1 6289.4 6340.2 6365.0 12:34:09 INFO - PROCESS | 1633 | 12:34:09 INFO - PROCESS | 1633 | [#1] ext.html Cycles:180 Average:49855.04 Median:17482.99 stddev:95136.31 (544.2%) stddev-sans-first:95331.82 12:34:09 INFO - PROCESS | 1633 | Values: 773.5 832.2 851.2 995.2 997.1 39712.8 40661.0 42734.1 42904.9 42960.4 31502.7 41187.0 41369.4 41429.5 41445.8 8298.8 8408.9 11324.3 11569.1 11798.9 11445.9 11575.5 11586.8 11587.6 11631.1 9483.2 11504.2 11537.8 11580.7 11583.7 11433.9 11511.5 11529.1 11539.7 11569.1 11227.3 11261.9 11280.4 11298.8 11334.8 29837.6 34165.0 35810.6 35985.6 36050.7 3945.2 3959.8 3973.4 3985.2 4021.4 26699.1 27616.0 27702.7 27706.0 27729.0 3174.5 3226.4 3233.7 3312.8 3425.2 380046.0 381388.0 382014.0 382161.0 383458.0 46037.0 46462.0 46561.5 46687.3 46700.1 48130.5 48472.5 48606.5 48648.0 48719.8 14649.6 14785.7 14841.8 14849.6 14856.9 12501.7 12570.8 12727.9 12759.6 12811.6 10981.0 12377.7 12382.5 12407.3 12487.9 4973.7 5847.1 5855.4 5866.9 5875.4 1141.8 1176.6 1179.1 1185.8 1212.0 28371.4 28567.4 28883.6 29014.7 29023.4 1265.4 1267.5 1300.3 1305.0 1324.1 10541.6 11190.2 14323.3 14503.3 14894.5 203099.0 203923.0 204270.0 204652.0 204928.0 58040.4 58393.7 58618.8 58966.0 59144.7 1103.4 1317.0 1503.0 1504.0 1511.0 26569.7 30209.8 31523.2 31946.4 32246.9 41335.0 43696.7 44292.6 44439.8 44468.8 445140.0 446477.0 446926.0 447210.0 447438.0 32650.5 33158.3 33173.3 33399.7 33423.8 45169.0 45987.0 46829.8 47218.3 47250.8 31296.5 31392.2 31647.8 32659.5 32870.2 31289.2 31459.0 31562.6 31791.5 32224.5 36697.9 37339.7 37490.8 37519.0 37525.0 30861.4 31642.8 32067.5 32665.8 32800.0 22317.6 23005.9 24279.4 24639.9 24713.6 12:34:09 INFO - PROCESS | 1633 | 12:34:09 INFO - PROCESS | 1633 | [#2] jquery.html Cycles:180 Average:43178.05 Median:21366.02 stddev:122259.96 (572.2%) stddev-sans-first:122560.78 12:34:09 INFO - PROCESS | 1633 | Values: 419.3 428.4 433.1 437.8 442.9 20777.0 21212.5 22309.7 22513.2 22640.7 18548.7 21975.7 22300.4 22894.8 23148.0 6832.8 6842.6 7120.0 7174.7 7188.0 6951.5 7101.7 7160.6 7163.3 7171.9 7091.4 7144.6 7565.1 7960.7 8069.8 6372.8 7934.8 7953.9 8013.6 8023.1 7722.6 7733.4 7777.7 7781.9 7820.7 25118.0 25958.0 26025.7 26640.0 26946.6 2410.4 2960.1 2969.4 3066.7 3155.8 15027.6 15734.8 16034.5 16231.5 16712.0 1838.3 2053.4 2108.6 2121.6 2147.8 152116.0 154920.0 160144.4 160992.0 163218.0 17222.7 20413.7 20903.7 21266.6 21465.5 32602.7 32664.2 33236.5 33340.7 33380.3 10484.1 10708.8 10886.2 10931.9 11015.4 9042.0 9046.5 9057.8 9114.6 9187.0 7731.0 9447.5 9679.4 9720.5 9725.5 3420.9 4009.4 4071.5 4081.8 4100.0 1038.3 1042.9 1070.7 1079.6 1096.2 19692.6 20387.1 21707.7 21828.8 21965.3 920.4 999.2 1079.1 1103.5 1132.5 10105.3 10370.5 11195.9 11271.4 11405.9 579739.0 663825.0 782517.0 790947.1 801824.0 125133.7 151587.2 167500.0 177051.0 180719.0 1314.1 1327.1 1365.9 1370.2 1370.3 18808.9 18957.4 19957.9 19982.4 19989.0 57781.7 60021.4 64380.0 68910.6 69065.7 96168.0 96910.5 97206.0 97499.5 99704.0 10953.3 11510.9 11745.3 11906.5 12038.3 12739.1 12797.2 12845.9 12963.5 13107.0 11090.6 11163.9 11291.0 11317.5 11341.7 10313.8 10518.6 10588.6 10689.3 10908.1 11337.5 11428.6 11469.9 11495.8 11769.1 11542.8 11659.5 11680.9 11685.8 11939.2 10817.9 11176.7 11204.4 11391.7 11428.3 12:34:09 INFO - PROCESS | 1633 | 12:34:09 INFO - PROCESS | 1633 | [#3] mootools.html Cycles:180 Average:6877.95 Median:2473.07 stddev:4251.98 (171.9%) stddev-sans-first:4244.35 12:34:09 INFO - PROCESS | 1633 | Values: 1450.2 1538.1 1613.5 1648.8 1663.5 11223.3 11612.5 11975.0 12001.5 12021.4 11604.7 11777.7 11829.4 11977.5 12001.4 2398.8 2449.9 2454.8 2455.4 2455.7 2448.5 2462.3 2508.1 2512.5 2516.1 2431.9 2464.8 2472.9 2475.4 2478.9 2430.1 2460.3 2471.6 2473.2 2478.3 2413.8 2439.0 2442.6 2448.1 2453.4 11483.7 11668.5 11751.0 11752.3 11756.7 5873.9 6021.9 6025.1 6025.3 6034.9 2867.9 3613.7 3665.5 3666.5 3674.8 1257.4 1264.6 1291.0 1312.3 1314.8 14276.5 14542.3 15174.8 15943.4 16057.4 4680.7 4774.3 4807.8 4813.3 4821.3 12078.6 12102.8 12175.9 12178.5 12233.0 5818.6 5844.7 5881.8 5944.9 5951.3 5305.0 5371.5 5432.0 5435.1 5443.6 3805.0 3973.3 3977.2 3982.0 4051.8 1045.8 1049.2 1063.0 1064.2 1065.1 819.7 935.4 945.4 946.4 970.9 5769.9 6621.4 6724.0 6808.5 6879.6 1001.3 1015.5 1019.7 1041.2 1041.5 4171.2 4179.3 4182.2 4189.7 4327.4 12644.7 13191.0 13223.7 13240.3 13252.6 13072.0 13127.7 13142.4 13180.5 13205.8 8366.8 9849.5 10199.3 10365.6 10373.7 6797.2 6938.1 7009.6 7103.4 7155.2 10166.0 11814.7 12334.4 12637.3 12716.4 11803.9 12075.9 12761.2 13241.6 13261.5 9273.5 9544.4 9606.3 9632.3 9636.6 10272.3 10574.8 10614.9 10663.0 10797.6 9077.6 9179.6 9202.2 9341.3 9364.3 8937.4 8998.8 9009.1 9058.3 9188.3 8179.9 8182.4 8264.4 8323.8 8333.0 5930.4 5971.6 5989.9 6115.0 6232.5 5979.8 6024.7 6198.2 6255.5 6313.2 12:34:09 INFO - PROCESS | 1633 | 12:34:09 INFO - PROCESS | 1633 | [#4] prototype.html Cycles:180 Average:4729.66 Median:5113.55 stddev:1338.08 (26.2%) stddev-sans-first:1301.66 12:34:09 INFO - PROCESS | 1633 | Values: 394.0 398.9 400.7 401.4 403.2 5360.5 5379.9 5489.5 5495.5 5496.6 4259.7 4308.1 4840.0 5183.3 5258.9 3692.7 3858.8 4427.3 4449.9 4466.8 3963.5 4276.7 4490.3 4494.6 4528.2 3998.9 4171.7 4369.9 4494.6 4502.8 3790.9 3878.9 4253.6 4273.2 4402.7 3307.7 3403.5 3679.4 4140.8 4240.6 4608.6 4628.4 4668.0 5207.6 5237.4 4362.8 4486.1 4685.3 4774.9 5147.2 5071.3 5724.2 5734.4 5748.7 5844.1 3989.5 4547.6 4553.0 4566.5 4604.5 4897.9 5410.6 5587.1 5642.0 5936.7 3917.7 4165.3 4471.6 4656.0 4738.5 4764.1 4771.9 4805.6 4808.7 4902.7 4807.6 4877.6 4878.0 5041.0 5123.3 5640.3 5674.6 5695.9 5705.3 5713.8 1433.0 1609.5 1617.8 1625.4 1627.5 1070.7 1123.6 1128.2 1156.9 1209.7 5396.7 5408.4 5422.9 5423.5 5434.8 5316.7 5670.8 5938.9 5986.0 6016.3 4662.1 4783.1 4814.6 5197.2 5416.2 2777.4 2790.4 3109.3 3127.5 3129.9 5543.1 5672.4 5689.6 6068.3 6142.8 5103.8 5127.4 5993.3 6046.8 6079.4 5459.9 5735.7 6096.9 6103.7 6161.9 5281.8 5437.4 5447.7 5880.2 5946.6 5450.0 5594.0 5889.9 5914.9 5962.8 5077.7 5089.3 5874.6 5903.1 6185.7 3820.4 4134.6 4658.4 4674.4 4695.9 5543.9 5839.6 5877.9 5885.6 5904.4 4918.7 4959.2 5077.6 5695.6 5733.9 5198.4 5238.0 5701.5 5735.5 5795.5 5225.6 5549.2 5618.9 5641.8 5663.7 4943.8 5628.0 5718.8 5756.2 5765.3 5250.3 5638.5 5650.9 5668.6 5691.9 12:34:09 INFO - PROCESS | 1633 | 12:34:09 INFO - PROCESS | 1633 | [#5] yui.html Cycles:180 Average:20046.83 Median:17377.61 stddev:27307.48 (157.1%) stddev-sans-first:27345.65 12:34:09 INFO - PROCESS | 1633 | Values: 750.7 762.9 798.9 867.0 872.6 15940.6 17291.9 17370.3 17384.9 17424.0 15972.4 16081.9 16106.9 16121.1 16135.7 993.8 1003.5 1036.7 1038.6 1039.8 1011.1 1024.4 1035.8 1037.0 1040.3 1062.5 1080.3 1080.3 1081.1 1082.9 1053.6 1073.9 1075.3 1079.4 1079.7 791.4 792.5 805.6 806.9 807.5 16899.0 17150.7 17538.6 18119.9 18236.7 14694.0 14707.9 14877.9 14878.4 15061.5 11806.5 12159.4 12257.1 12273.2 12288.1 3293.2 3824.3 3858.4 3875.3 3968.6 95673.5 102494.0 107487.5 108870.0 109846.0 9861.7 11607.8 11630.9 11679.0 11727.7 23163.4 23429.9 23468.8 23482.3 23601.8 6794.4 8196.0 8403.3 8432.7 8685.3 4454.8 4470.3 4506.2 4538.5 4568.2 3674.9 3738.6 3761.9 3779.7 3806.4 688.3 690.3 690.5 697.9 705.7 684.5 701.8 718.3 722.8 741.7 10540.6 10648.4 10791.6 10913.9 11002.4 658.4 699.7 705.0 714.6 721.4 5026.6 5223.1 5239.3 5244.7 5273.0 69141.7 83652.2 98641.0 101328.0 104286.0 75298.1 75426.0 76133.0 76811.0 77249.0 68039.0 69822.0 78256.0 78334.6 78376.0 10256.6 10934.2 11231.1 11375.8 11521.4 45324.3 47299.0 49700.8 50034.7 53257.5 62865.7 64805.7 65506.7 66039.0 66635.3 10833.0 13199.7 13293.9 13705.8 13714.9 14773.8 15141.8 15728.1 15758.4 16266.3 14099.9 14503.7 14504.3 14855.7 14973.5 13922.5 14422.8 14452.8 14514.2 15023.3 13732.6 14310.9 14324.6 14327.6 14922.7 9973.8 10518.4 10520.4 10574.4 10585.8 8836.5 9324.4 11109.3 11460.4 11694.0 12:34:09 INFO - PROCESS | 1633 | -------- Summary: end -------- 12:34:09 INFO - PROCESS | 1633 | 12:34:10 INFO - TEST-INFO | 1633: exit 0 12:34:10 INFO - TEST-OK | dromaeo_css | took 1115922ms 12:34:10 INFO - TEST-START | kraken 12:34:10 INFO - operating with platform_type : mac_ 12:34:10 INFO - Initialising browser for kraken test... 12:34:10 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpf_ERiv/profile http://localhost:49223/getInfo.html) 12:34:12 INFO - PROCESS | 1645 | 2017-05-18 12:34:12.916 plugin-container[1647:10166] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 12:34:12 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:34:12 INFO - PROCESS | 1645 | 2017-05-18 12:34:12.917 plugin-container[1647:10166] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 12:34:12 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:34:12 INFO - PROCESS | 1645 | 2017-05-18 12:34:12.917 plugin-container[1647:10166] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:34:12 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 12:34:12 INFO - PROCESS | 1645 | colorDepth:24 12:34:12 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 12:34:12 INFO - PROCESS | 1645 | __metrics 12:34:13 ERROR - PROCESS | 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 12:34:13 INFO - TEST-INFO | 1645: exit 0 12:34:13 INFO - Browser initialized. 12:34:13 INFO - Running cycle 1/1 for kraken test... 12:34:13 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpf_ERiv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:34:16 INFO - PROCESS | 1648 | 2017-05-18 12:34:16.035 plugin-container[1649:10288] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 12:34:16 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:34:16 INFO - PROCESS | 1648 | 2017-05-18 12:34:16.036 plugin-container[1649:10288] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b03, name = 'com.apple.CFPasteboardClient' 12:34:16 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:34:16 INFO - PROCESS | 1648 | 2017-05-18 12:34:16.036 plugin-container[1649:10288] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:34:25 INFO - PROCESS | 1648 | RSS: Main: 174309376 12:34:25 INFO - PROCESS | 1648 | 12:34:28 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-beat-detection) 12:34:29 INFO - PROCESS | 1648 | RSS: Main: 187686912 12:34:29 INFO - PROCESS | 1648 | 12:34:32 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-dft) 12:34:32 INFO - PROCESS | 1648 | RSS: Main: 195596288 12:34:32 INFO - PROCESS | 1648 | 12:34:36 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-fft) 12:34:36 INFO - PROCESS | 1648 | RSS: Main: 199434240 12:34:36 INFO - PROCESS | 1648 | 12:34:38 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-oscillator) 12:34:39 INFO - PROCESS | 1648 | RSS: Main: 200007680 12:34:39 INFO - PROCESS | 1648 | 12:34:41 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:34:41 INFO - PROCESS | 1648 | RSS: Main: 201068544 12:34:41 INFO - PROCESS | 1648 | 12:34:45 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-darkroom) 12:34:45 INFO - PROCESS | 1648 | RSS: Main: 204972032 12:34:45 INFO - PROCESS | 1648 | 12:34:48 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-desaturate) 12:34:49 INFO - PROCESS | 1648 | RSS: Main: 204709888 12:34:49 INFO - PROCESS | 1648 | 12:34:52 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49223/tests/kraken/driver.html?testName=json-parse-financial) 12:34:53 INFO - PROCESS | 1648 | RSS: Main: 204603392 12:34:53 INFO - PROCESS | 1648 | 12:34:55 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49223/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:34:55 INFO - PROCESS | 1648 | RSS: Main: 204292096 12:34:55 INFO - PROCESS | 1648 | 12:34:57 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:34:58 INFO - PROCESS | 1648 | RSS: Main: 204288000 12:34:58 INFO - PROCESS | 1648 | 12:35:00 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:35:01 INFO - PROCESS | 1648 | RSS: Main: 204353536 12:35:01 INFO - PROCESS | 1648 | 12:35:04 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:35:05 INFO - PROCESS | 1648 | RSS: Main: 204673024 12:35:05 INFO - PROCESS | 1648 | 12:35:07 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:35:08 INFO - PROCESS | 1648 | RSS: Main: 204455936 12:35:08 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49223/tests/kraken/driver.html?testName=ai-astar) 12:35:10 INFO - PROCESS | 1648 | RSS: Main: 206499840 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | __start_tp_report 12:35:10 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 12:35:10 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 12:35:10 INFO - PROCESS | 1648 | |i|pagename|runs| 12:35:10 INFO - PROCESS | 1648 | |0;ai-astar;109;123;119;122;121;120;118;119;120;119 12:35:10 INFO - PROCESS | 1648 | |1;audio-beat-detection;141;111;108;147;112;144;146;122;127;121 12:35:10 INFO - PROCESS | 1648 | |2;audio-dft;200;202;197;196;193;199;197;198;198;200 12:35:10 INFO - PROCESS | 1648 | |3;audio-fft;81;58;58;83;61;62;60;62;72;60 12:35:10 INFO - PROCESS | 1648 | |4;audio-oscillator;88;70;84;72;71;72;99;104;103;106 12:35:10 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;91;108;136;102;129;136;95;135;135;92 12:35:10 INFO - PROCESS | 1648 | |6;imaging-darkroom;122;110;101;154;93;153;153;96;95;96 12:35:10 INFO - PROCESS | 1648 | |7;imaging-desaturate;94;79;136;123;121;98;144;125;137;148 12:35:10 INFO - PROCESS | 1648 | |8;json-parse-financial;63;66;64;67;67;66;68;69;67;68 12:35:10 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;55;63;75;56;53;52;67;74;48;55 12:35:10 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;94;95;102;74;84;78;104;102;105;101 12:35:10 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;159;135;137;150;151;157;143;146;207;168 12:35:10 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;164;165;162;160;166;166;172;167;165;164 12:35:10 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;62;66;71;79;86;70;63;64;64;63 12:35:10 INFO - PROCESS | 1648 | __end_tp_report 12:35:10 INFO - PROCESS | 1648 | __start_cc_report 12:35:10 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1016 12:35:10 INFO - PROCESS | 1648 | __end_cc_report 12:35:10 INFO - PROCESS | 1648 | __startTimestamp1495136110554__endTimestamp 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | ------- Summary: start ------- 12:35:10 INFO - PROCESS | 1648 | Number of tests: 14 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:119.00 Median:120.00 stddev:3.83 (3.2%) stddev-sans-first:1.62 12:35:10 INFO - PROCESS | 1648 | Values: 109.0 123.0 119.0 122.0 121.0 120.0 118.0 119.0 120.0 119.0 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:134.00 stddev:15.42 (11.5%) stddev-sans-first:15.61 12:35:10 INFO - PROCESS | 1648 | Values: 141.0 111.0 108.0 147.0 112.0 144.0 146.0 122.0 127.0 121.0 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:198.00 Median:198.50 stddev:2.49 (1.3%) stddev-sans-first:2.54 12:35:10 INFO - PROCESS | 1648 | Values: 200.0 202.0 197.0 196.0 193.0 199.0 197.0 198.0 198.0 200.0 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:65.70 Median:62.00 stddev:9.46 (15.3%) stddev-sans-first:8.26 12:35:10 INFO - PROCESS | 1648 | Values: 81.0 58.0 58.0 83.0 61.0 62.0 60.0 62.0 72.0 60.0 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:86.90 Median:72.00 stddev:15.11 (21.0%) stddev-sans-first:16.02 12:35:10 INFO - PROCESS | 1648 | Values: 88.0 70.0 84.0 72.0 71.0 72.0 99.0 104.0 103.0 106.0 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:115.90 Median:136.00 stddev:19.98 (14.7%) stddev-sans-first:19.05 12:35:10 INFO - PROCESS | 1648 | Values: 91.0 108.0 136.0 102.0 129.0 136.0 95.0 135.0 135.0 92.0 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:117.30 Median:123.50 stddev:26.31 (21.3%) stddev-sans-first:27.86 12:35:10 INFO - PROCESS | 1648 | Values: 122.0 110.0 101.0 154.0 93.0 153.0 153.0 96.0 95.0 96.0 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:120.50 Median:146.00 stddev:23.04 (15.8%) stddev-sans-first:22.36 12:35:10 INFO - PROCESS | 1648 | Values: 94.0 79.0 136.0 123.0 121.0 98.0 144.0 125.0 137.0 148.0 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:66.50 Median:67.00 stddev:1.84 (2.7%) stddev-sans-first:1.45 12:35:10 INFO - PROCESS | 1648 | Values: 63.0 66.0 64.0 67.0 67.0 66.0 68.0 69.0 67.0 68.0 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:59.50 stddev:9.44 (15.9%) stddev-sans-first:9.85 12:35:10 INFO - PROCESS | 1648 | Values: 55.0 63.0 75.0 56.0 53.0 52.0 67.0 74.0 48.0 55.0 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:93.90 Median:76.00 stddev:11.33 (14.9%) stddev-sans-first:12.02 12:35:10 INFO - PROCESS | 1648 | Values: 94.0 95.0 102.0 74.0 84.0 78.0 104.0 102.0 105.0 101.0 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:155.30 Median:154.00 stddev:20.77 (13.5%) stddev-sans-first:21.99 12:35:10 INFO - PROCESS | 1648 | Values: 159.0 135.0 137.0 150.0 151.0 157.0 143.0 146.0 207.0 168.0 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.10 Median:165.50 stddev:3.18 (1.9%) stddev-sans-first:3.35 12:35:10 INFO - PROCESS | 1648 | Values: 164.0 165.0 162.0 160.0 166.0 166.0 172.0 167.0 165.0 164.0 12:35:10 INFO - PROCESS | 1648 | 12:35:10 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:68.00 stddev:7.98 (11.7%) stddev-sans-first:8.08 12:35:10 INFO - PROCESS | 1648 | Values: 62.0 66.0 71.0 79.0 86.0 70.0 63.0 64.0 64.0 63.0 12:35:10 INFO - PROCESS | 1648 | -------- Summary: end -------- 12:35:10 INFO - PROCESS | 1648 | 12:35:11 INFO - TEST-INFO | 1648: exit 0 12:35:11 INFO - TEST-OK | kraken | took 61112ms 12:35:11 INFO - SUITE-END | took 1177s 12:35:11 INFO - Completed test suite (00:19:37) 12:35:11 INFO - javascript benchmark 12:35:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7930.617821680076, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3611.4402267648506, 3622.728295377847, 3624.601293857675, 3641.4173732202844, 3645.343838109141, 5968.806238752245, 6231.252249730033, 6232.844178895524, 6254.43710066094, 6263.185785847839, 5383.192521121834, 6212.1303017577575, 6234.84412889678, 6257.280412752535, 6263.749450021994, 4230.555791641879, 5258.1849813279005, 5658.179563963223, 5699.344575373823, 5759.712014399284, 5732.48407643312, 5753.424657534234, 5761.884762304751, 5761.897195519171, 5769.336526299488, 4611.377464042362, 5525.861853453655, 5543.77824887004, 5581.944180558183, 5727.312722473309, 5646.745896434671, 5700.971495142539, 5701.2018317435795, 5757.769689212427, 5764.654120752726, 5677.290338707661, 5682.374938756733, 5744.368119506851, 5750.453706897838, 5756.33802112754, 6233.470155036782, 6273.12176295319, 6283.654399008053, 6306.590071645374, 6320.241571011508, 5313.840584782462, 6300.086487459339, 6363.109164716943, 6509.0, 6551.4431273341825, 6745.426638735713, 6747.831304217385, 6751.358620931003, 6753.3921947025, 6759.797206083826, 6311.274203466568, 6322.557420980487, 6340.3342649022115, 6355.142055822497, 6373.904391434132, 6559.6064236145985, 6632.369924857131, 6683.532152749358, 6773.424258937947, 6839.213490448563, 6501.414872661483, 6554.377334153248, 6566.770163044271, 6574.243961944438, 6576.309487503743, 5530.751116199781, 6684.9331506685285, 6689.5986240825705, 6693.799186024427, 6709.865802684017, 5200.791968321225, 6331.651759153198, 6546.214454265423, 6637.834054148589, 6660.034295027194, 6528.281888992207, 6572.638504882278, 6590.077389165521, 6596.472282217411, 6598.670066496656, 4378.365137055109, 5235.764390602432, 5311.123664595299, 5358.91961620576, 5362.570994320445, 4778.0, 4794.880127996758, 4802.639802014863, 4839.7096174229655, 4857.878553036132, 6049.606775559574, 6351.396617321347, 6361.459275961595, 6376.266729326094, 6385.680715964183, 6579.3420657934785, 6585.111010013587, 6588.4729221662155, 6602.900956485657, 6742.224644165885, 6307.369263073656, 6308.716107775161, 6317.6209427434505, 6319.778807741707, 6330.873382532323, 5908.734106965197, 5919.644821310735, 5932.0, 5936.309281017152, 6002.459778619946, 6640.302768209269, 6690.866182676416, 6819.965900170468, 6827.146606674166, 6882.346177113166, 6683.799486015428, 6689.832754181184, 6735.0570920072205, 6747.696353663999, 6753.425958793558, 6921.204061532787, 6929.89605155913, 6953.860922781617, 6963.4777391694815, 6967.233604303421, 6332.0, 6405.807825765234, 6550.508711846535, 6619.139511863427, 6638.767643132467, 5776.0, 6278.0, 6677.399034086761, 6744.123263975652, 6753.864922701617, 6488.188976377953, 6521.282658907611, 6538.346165383424, 6539.280679125388, 6543.116679248241, 6267.373262673878, 6313.873722525432, 6409.9038514423255, 6473.352664733489, 6548.345165483413, 6316.936830631818, 6340.461060809698, 6410.391012853772, 6455.870882582416, 6459.063435801689, 6532.085508028975, 6539.215294164731, 6561.737530498726, 6563.967180164069, 6576.177977752781, 6238.064290356483, 6253.1245625613365, 6289.371062893674, 6340.175777148941, 6365.04524321337], "value": 6098.192991846003, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.5204173412482, 832.2260297922932, 851.1956201389685, 995.1541189988507, 997.0926456924201, 39712.80143599281, 40661.0, 42734.14531709364, 42904.9273768156, 42960.35559466603, 31502.684973150263, 41187.0, 41369.37945930813, 41429.549965751205, 41445.792771036104, 8298.751037468886, 8408.915910840906, 11324.32054076754, 11569.074474042098, 11798.94100529496, 11445.885541144566, 11575.53697852085, 11586.768264634702, 11587.594434194805, 11631.069514438866, 9483.193928516084, 11504.194706370557, 11537.769244615145, 11580.71048223797, 11583.652490425258, 11433.942830285838, 11511.481983310729, 11529.135314851335, 11539.711507212303, 11569.13231507636, 11227.326360418401, 11261.943690281496, 11280.379579123144, 11298.774024519545, 11334.773304533946, 29837.552436713468, 34164.975050748515, 35810.64189358099, 35985.64014359875, 36050.73822416229, 3945.230680017398, 3959.821808018617, 3973.384125460558, 3985.183037477339, 4021.356582946744, 26699.065532706456, 27616.0, 27702.722972770214, 27706.0, 27729.0, 3174.460341741886, 3226.4192445359827, 3233.676632336771, 3312.751543634237, 3425.2293234022395, 380046.0, 381388.0, 382014.0, 382161.0, 383458.0, 46037.0, 46462.0, 46561.5343846564, 46687.29969050467, 46700.065998679835, 48130.51869481261, 48472.51527484751, 48606.51393486091, 48648.0, 48719.75640121777, 14649.633759155893, 14785.704285914222, 14841.812654987574, 14849.628759280888, 14856.851431485762, 12501.687457813627, 12570.811437828439, 12727.918126959126, 12759.61721148367, 12811.551595694105, 10981.0, 12377.690557736129, 12382.504699812087, 12407.317597532223, 12487.93756031214, 4973.731698416953, 5847.064469684915, 5855.3851845556455, 5866.911996320058, 5875.353711091862, 1141.8401423800674, 1176.5705517486226, 1179.121554441929, 1185.7805808534292, 1211.9939400302942, 28371.432571348458, 28567.42879141667, 28883.56674649882, 29014.709852901622, 29023.41953160925, 1265.373640048182, 1267.5436842736608, 1300.3303298801125, 1304.9804252936215, 1324.0929962975392, 10541.631042913614, 11190.160737944687, 14323.283835808168, 14503.274836258144, 14894.478693245685, 203099.0, 203923.0, 204270.0, 204652.0, 204928.0, 58040.4195958035, 58393.70803145957, 58618.827623447294, 58966.0, 59144.70427647834, 1103.4372470040178, 1316.973660526784, 1502.9924850375678, 1503.9849601504063, 1510.9924450377898, 26569.67151642449, 30209.848950755106, 31523.21191970219, 31946.36107277888, 32246.871359503242, 41335.0, 43696.68909932707, 44292.55707442884, 44439.77780111079, 44468.777656112805, 445140.0, 446477.0, 446926.0, 447210.0, 447438.0, 32650.51024234686, 33158.33683326368, 33173.33653326872, 33399.666003339655, 33423.830165945044, 45169.0, 45987.0, 46829.76585117052, 47218.29172562483, 47250.76374618105, 31296.530552042193, 31392.215194620316, 31647.84176079105, 32659.510107347935, 32870.178245544055, 31289.217769555944, 31459.0, 31562.579683914737, 31791.52312715265, 32224.516632251005, 36697.899063028155, 37339.69311074208, 37490.81254593709, 37519.0, 37525.0, 30861.38277234398, 31642.841785790923, 32067.518987214742, 32665.836670816494, 32800.0, 22317.553648926605, 23005.88497057504, 24279.393015174057, 24639.876800615883, 24713.629295560942], "value": 18884.737901041382, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.2662840029948, 428.4430240687106, 433.05593805504, 437.78248967979187, 442.8870637987315, 20777.0, 21212.46968825778, 22309.665355019686, 22513.212037578687, 22640.66039009414, 18548.721769173473, 21975.670364944537, 22300.442488937744, 22894.77105228947, 23147.958341874615, 6832.760853370109, 6842.555233909792, 7120.003199552041, 7174.677139528734, 7187.964060179692, 6951.513394062417, 7101.680424380889, 7160.64196790161, 7163.31948464895, 7171.856562868739, 7091.361777440029, 7144.607046612434, 7565.13001004886, 7960.681572737112, 8069.838603227932, 6372.776952806676, 7934.841303173933, 7953.880691789628, 8013.639386227634, 8023.077346105185, 7722.575258360788, 7733.41999350051, 7777.688892444296, 7781.922180778233, 7820.687172513092, 25118.0, 25958.0, 26025.73974260252, 26640.0, 26946.595801063, 2410.3853517353136, 2960.141558947924, 2969.376430949503, 3066.693330666938, 3155.794873333249, 15027.624309392353, 15734.842651573452, 16034.518964431085, 16231.513054608262, 16712.0, 1838.347386677737, 2053.3839848045677, 2108.620448319302, 2121.586290673319, 2147.7530084040222, 152116.0, 154920.0, 160144.39855601412, 160992.0, 163218.0, 17222.741658875126, 20413.67311124772, 20903.68644470334, 21266.574668506542, 21465.46336341604, 32602.673973260436, 32664.183395414828, 33236.50145247823, 33340.66659333376, 33380.332393352, 10484.10885074762, 10708.78582428347, 10886.183536234814, 10931.945340273407, 11015.394153321484, 9041.954790226007, 9046.502442365732, 9057.77355566103, 9114.58984345706, 9187.0, 7730.96134519335, 9447.480388578557, 9679.370840895319, 9720.465374404475, 9725.513724313756, 3420.863165473403, 4009.4186342980106, 4071.491063617048, 4081.7550347309457, 4099.959000409957, 1038.325088692356, 1042.8957104289511, 1070.662741236508, 1079.5897558927713, 1096.2107282756403, 19692.60614787725, 20387.082581283878, 21707.674384884238, 21828.781712182994, 21965.26599461005, 920.40173886974, 999.2006394884064, 1079.1474734959452, 1103.4937310571013, 1132.4847194526471, 10105.343152695052, 10370.481475926174, 11195.944020280009, 11271.38007409584, 11405.942970285096, 579739.0, 663825.0, 782517.0, 790947.0905290989, 801824.0, 125133.74866251221, 151587.24206379117, 167500.0, 177051.0, 180719.0, 1314.086709736743, 1327.1108357400565, 1365.9317034148253, 1370.225822410335, 1370.2532119994628, 18808.905955470134, 18957.43127706171, 19957.900210498854, 19982.400527984184, 19989.0, 57781.711091444275, 60021.39978600333, 64380.0, 68910.62178756497, 69065.65467172833, 96168.0, 96910.51544742231, 97206.0, 97499.53750693603, 99704.0, 10953.34279943206, 11510.884891151316, 11745.29528228309, 11906.464209110783, 12038.337891416057, 12739.108262421541, 12797.168184068007, 12845.871541284467, 12963.546275880299, 13107.016973727199, 11090.611828585961, 11163.88836111661, 11291.031945943758, 11317.490712917774, 11341.716457088638, 10313.793724125326, 10518.579256829948, 10588.576456941637, 10689.30519516229, 10908.127349811837, 11337.546498139982, 11428.599998999995, 11469.942650286695, 11495.8275625864, 11769.117316201491, 11542.84571542839, 11659.53361865516, 11680.94159529197, 11685.824712629488, 11939.223950443193, 10817.891821081688, 11176.664700059011, 11204.43977801123, 11391.658250252505, 11428.25716328436], "value": 11934.037379096455, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.216882883243, 1538.1078974194966, 1613.540141059798, 1648.7526870969361, 1663.4593757028965, 11223.270487418313, 11612.477438515267, 11974.982126519246, 12001.459934302966, 12021.398930053485, 11604.709882252948, 11777.70555736107, 11829.40852957353, 11977.520899164023, 12001.399930003487, 2398.784109430151, 2449.9265022049276, 2454.754524547549, 2455.4475243070347, 2455.6807615010025, 2448.5198213509325, 2462.322861213163, 2508.147229941819, 2512.472380800034, 2516.144510866305, 2431.914882979096, 2464.8151388645833, 2472.9010839566395, 2475.356407334097, 2478.913238036669, 2430.1251549442186, 2460.2865169100937, 2471.5798314286612, 2473.245660073677, 2478.293686299414, 2413.8189635777385, 2438.975610243893, 2442.6213936839818, 2448.1309135257084, 2453.4479742058074, 11483.655490335303, 11668.533258669642, 11751.001164900994, 11752.294862308288, 11756.706082347924, 5873.911891321633, 6021.939780602182, 6025.0661147522205, 6025.307089684698, 6034.879302413927, 2867.899623513188, 3613.728970327236, 3665.5234819473562, 3666.4866918631415, 3674.761140525857, 1257.4215860704087, 1264.576366917084, 1290.9935450322782, 1312.343828085957, 1314.8027795830606, 14276.500322488768, 14542.272886355746, 15174.848251517455, 15943.362265509366, 16057.357705691758, 4680.695754775997, 4774.283857421345, 4807.831725889647, 4813.253945638432, 4821.349117869043, 12078.637640870787, 12102.81845772314, 12175.878241217475, 12178.512859485698, 12233.0, 5818.631696152419, 5844.678542680108, 5881.823545293648, 5944.940550594525, 5951.315598706205, 5304.9920515102, 5371.4897084776885, 5432.0, 5435.10320797064, 5443.646162999472, 3805.040404191843, 3973.304671682444, 3977.1846771411792, 3982.0, 4051.7974101294817, 1045.7542477517777, 1049.1711547877244, 1062.9574817007233, 1064.180580952662, 1065.0680654427376, 819.6598411659214, 935.4340623922565, 945.3807755919884, 946.4321407155652, 970.8883478399933, 5769.88460230793, 6621.370969757865, 6723.966380168068, 6808.523403361816, 6879.552829066095, 1001.33911618333, 1015.5176291261591, 1019.6846068571459, 1041.1514615588271, 1041.5052849896238, 4171.186618609342, 4179.310413781692, 4182.205380977604, 4189.685773566995, 4327.394164816928, 12644.683882902817, 13191.0, 13223.669408264677, 13240.337983100999, 13252.602421927357, 13072.019598530149, 13127.737445251232, 13142.4085916134, 13180.472781088842, 13205.801912971314, 8366.828643989724, 9849.45827979468, 10199.28604997658, 10365.585376584851, 10373.740656483498, 6797.218319893275, 6938.098047253825, 7009.649517524002, 7103.431725462054, 7155.177154627077, 10166.0, 11814.700382958039, 12334.444949977093, 12637.304948227937, 12716.364181790688, 11803.937645611935, 12075.879241207827, 12761.170523916287, 13241.602751917459, 13261.469541218243, 9273.53632318395, 9544.42733435996, 9606.327557070937, 9632.27757918145, 9636.614535418505, 10272.280940334105, 10574.84137737919, 10614.89385106139, 10663.0, 10797.62208322705, 9077.591508382007, 9179.632814687338, 9202.217811486098, 9341.34610577253, 9364.250859931053, 8937.374383793333, 8998.775030624287, 9009.0990900912, 9058.275337972815, 9188.264938804747, 8179.877301840358, 8182.427230094075, 8264.421490495608, 8323.750287491384, 8333.0, 5930.436608522184, 5971.641701497924, 5989.940100598938, 6114.969425152845, 6232.501399888089, 5979.820605381846, 6024.668643224665, 6198.194234749454, 6255.530835187288, 6313.179286692701], "value": 5258.321366037818, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.9980300098499, 398.9481367422235, 400.7435241445475, 401.3578274760384, 403.22983102274645, 5360.517553420192, 5379.865503362418, 5489.533389661879, 5495.505404513598, 5496.615236933406, 4259.744415335082, 4308.104434049016, 4839.975800120995, 5183.326167598207, 5258.92111618326, 3692.7230457715646, 3858.8070596470207, 4427.343593843684, 4449.933251001237, 4466.79899404526, 3963.454252841394, 4276.6792490563175, 4490.281554951209, 4494.573015563533, 4528.1698938163645, 3998.900027499307, 4171.749695018309, 4369.924285964174, 4494.617957473619, 4502.752348620825, 3790.8673196438267, 3878.9224215515815, 4253.595908388698, 4273.247968332984, 4402.713823601455, 3307.684620765474, 3403.506491558735, 3679.3929001714687, 4140.772257525835, 4240.597143271384, 4608.585227329523, 4628.4445866496235, 4668.043051174501, 5207.60942929278, 5237.371515418136, 4362.8036738346855, 4486.102779444124, 4685.320628508881, 4774.856754297377, 5147.150720131174, 5071.315372424712, 5724.198612194296, 5734.3657057738765, 5748.7125643717645, 5844.064949608042, 3989.501312335958, 4547.590716835501, 4552.954470455286, 4566.54334566544, 4604.52655150352, 4897.902041959176, 5410.648307860016, 5587.05020146576, 5641.971790141064, 5936.7328470218945, 3917.7061720371084, 4165.291900376883, 4471.552844715502, 4655.976720116378, 4738.47876733559, 4764.118638051971, 4771.904561908812, 4805.6155507559315, 4808.711477311373, 4902.7058376497525, 4807.61539076873, 4877.561019508261, 4877.951220487821, 5041.0, 5123.334916152191, 5640.251522028185, 5674.574406919498, 5695.914561281584, 5705.286839145107, 5713.8285851424525, 1433.0470237292084, 1609.4849648112727, 1617.781599484078, 1625.4392234679017, 1627.5361521966183, 1070.699100592768, 1123.6067376418353, 1128.2158899564888, 1156.888678162357, 1209.727811242481, 5396.703181325065, 5408.3509978802795, 5422.945770542323, 5423.511883930465, 5434.809781657702, 5316.680999140064, 5670.8015219467125, 5938.871614393252, 5986.0, 6016.278046634432, 4662.081504013298, 4783.051618582261, 4814.566688997937, 5197.245459906256, 5416.187571864252, 2777.402858385457, 2790.3582176099203, 3109.3470371222297, 3127.5308703694172, 3129.937401251962, 5543.059929024889, 5672.376038635747, 5689.630174038757, 6068.33248342682, 6142.754289828446, 5103.77033033507, 5127.435982041972, 5993.250843644544, 6046.762697801842, 6079.422454866781, 5459.918101228484, 5735.713214339266, 6096.878062438726, 6103.694815259308, 6161.876762464725, 5281.781651706369, 5437.429069947599, 5447.6769170806565, 5880.235569375954, 5946.583739138218, 5450.0, 5593.972030139823, 5889.882202356014, 5914.911276330772, 5962.763963513682, 5077.695338279715, 5089.312942752681, 5874.647521148574, 5903.144044113494, 6185.721642526187, 3820.3887378019385, 4134.635570261747, 4658.440987081572, 4674.392328997209, 4695.859124226278, 5543.9168412474655, 5839.620424672383, 5877.853053673693, 5885.587459009893, 5904.380040095728, 4918.680285781413, 4959.20652695567, 5077.568406685473, 5695.601307908407, 5733.913991290217, 5198.428172901054, 5237.978594174099, 5701.458361455655, 5735.541156707537, 5795.53689681892, 5225.555827754683, 5549.196511133979, 5618.876224754984, 5641.771500257458, 5663.658441519523, 4943.802247910044, 5628.015097358028, 5718.79984200551, 5756.222909907109, 5765.250517432707, 5250.343707036621, 5638.4925356718095, 5650.943490565041, 5668.574856885668, 5691.8861622768145], "value": 4340.841112403642, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.6697053296552, 762.8550575390672, 798.872180451128, 866.9653213871445, 872.6378552900546, 15940.601484962866, 17291.913540432313, 17370.305187792488, 17384.913075434604, 17424.0, 15972.361105555763, 16081.919590402062, 16106.919465402656, 16121.113338766363, 16135.677286454293, 993.8310487217191, 1003.4546797931196, 1036.7097212780434, 1038.5586125896502, 1039.807635587415, 1011.1102230037557, 1024.4314405504979, 1035.7824856780085, 1037.035556932053, 1040.266612038511, 1062.5059347403455, 1080.3409919948824, 1080.346390433784, 1081.0918828184324, 1082.880883102858, 1053.6470282455386, 1073.8711354637494, 1075.2580719303694, 1079.3631757263213, 1079.6545105566222, 791.4064451661254, 792.4928046050533, 805.6173317674117, 806.8951036365294, 807.4872455990429, 16899.0, 17150.65698686032, 17538.649227015387, 18119.909400453045, 18236.726449103273, 14694.0, 14707.852921470756, 14877.925610371878, 14878.376287244628, 15061.548153555412, 11806.46870890812, 12159.419275494218, 12257.14200006, 12273.202241854251, 12288.139830211889, 3293.2425542125447, 3824.349860523718, 3858.3826587745825, 3875.341191997339, 3968.5832987536182, 95673.52163239139, 102493.97506024918, 107487.46256268746, 108870.01933464917, 109846.0, 9861.704148875546, 11607.76784464306, 11630.88369116298, 11679.0, 11727.706807329885, 23163.420914477272, 23429.94565244568, 23468.765312347, 23482.295531134092, 23601.7639823603, 6794.422474089659, 8195.95902020486, 8403.327733781283, 8432.662693492191, 8685.348598854986, 4454.77726113693, 4470.284754439274, 4506.211413002711, 4538.546145385501, 4568.17772800899, 3674.853005879735, 3738.626137386294, 3761.946654936622, 3779.7354185206773, 3806.410006449005, 688.2532452289222, 690.3027941778739, 690.5131882023162, 697.9197392299849, 705.7388766156555, 684.5071548485083, 701.8314506346977, 718.2781304788758, 722.7903907866765, 741.661281503202, 10540.613420955497, 10648.361098334124, 10791.6222932197, 10913.890861091446, 11002.449877506253, 658.4271683635268, 699.7166147710185, 705.0, 714.6248219684708, 721.4480922094601, 5026.597872170218, 5223.13818219997, 5239.345081864767, 5244.711540865289, 5272.99813035522, 69141.65429172822, 83652.16347836444, 98641.0, 101328.0, 104286.0, 75298.11754706067, 75426.0, 76133.0, 76811.0, 77249.0, 68038.99999999901, 69822.0, 78256.0, 78334.608326958, 78376.0, 10256.589736410759, 10934.179936504954, 11231.058533236413, 11375.829362559403, 11521.366324851879, 45324.32013519866, 47299.0, 49700.75149624229, 50034.74913127201, 53257.46742532525, 62865.68567157135, 64805.67597161984, 65506.67246663736, 66039.0, 66635.33364666291, 10833.0, 13199.67000824987, 13293.867061329263, 13705.794413084011, 13714.862851371758, 14773.778393323893, 15141.848581514485, 15728.13495257726, 15758.448454304043, 16266.349346026027, 14099.859001409855, 14503.709925801635, 14504.347304371118, 14855.702885942437, 14973.475928342892, 13922.51271205503, 14422.77406420424, 14452.783208251674, 14514.201718905138, 15023.323950422038, 13732.588022359345, 14310.85689143137, 14324.57026289213, 14327.57017289483, 14922.701545969237, 9973.76373421227, 10518.421486818299, 10520.368777873353, 10574.41840698769, 10585.788284234119, 8836.513991730517, 9324.393914395814, 11109.277896936997, 11460.426978651201, 11694.0], "value": 7930.163937211434, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1560.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 123.0, 119.0, 122.0, 121.0, 120.0, 118.0, 119.0, 120.0, 119.0], "value": 119.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 111.0, 108.0, 147.0, 112.0, 144.0, 146.0, 122.0, 127.0, 121.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 202.0, 197.0, 196.0, 193.0, 199.0, 197.0, 198.0, 198.0, 200.0], "value": 198.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 58.0, 83.0, 61.0, 62.0, 60.0, 62.0, 72.0, 60.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 84.0, 72.0, 71.0, 72.0, 99.0, 104.0, 103.0, 106.0], "value": 86.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 108.0, 136.0, 102.0, 129.0, 136.0, 95.0, 135.0, 135.0, 92.0], "value": 115.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 110.0, 101.0, 154.0, 93.0, 153.0, 153.0, 96.0, 95.0, 96.0], "value": 117.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 79.0, 136.0, 123.0, 121.0, 98.0, 144.0, 125.0, 137.0, 148.0], "value": 120.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 64.0, 67.0, 67.0, 66.0, 68.0, 69.0, 67.0, 68.0], "value": 66.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 63.0, 75.0, 56.0, 53.0, 52.0, 67.0, 74.0, 48.0, 55.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 102.0, 74.0, 84.0, 78.0, 104.0, 102.0, 105.0, 101.0], "value": 93.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 135.0, 137.0, 150.0, 151.0, 157.0, 143.0, 146.0, 207.0, 168.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 162.0, 160.0, 166.0, 166.0, 172.0, 167.0, 165.0, 164.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 71.0, 79.0, 86.0, 70.0, 63.0, 64.0, 64.0, 63.0], "value": 68.8, "unit": "score"}]}]} 12:35:11 INFO - Return code: 0 12:35:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:35:11 ERROR - # TBPL SUCCESS # 12:35:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:35:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:35:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:35:11 INFO - 2017-05-18 12:35:11.910 screenresolution[1653:10660] starting screenresolution argv=screenresolution get 12:35:11 INFO - 2017-05-18 12:35:11.912 screenresolution[1653:10660] Display 0: 1600x1200x32@60 12:35:11 INFO - 2017-05-18 12:35:11.918 screenresolution[1654:10663] starting screenresolution argv=screenresolution list 12:35:11 INFO - Available Modes on Display 0 12:35:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:35:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:35:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:35:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:35:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:35:12 INFO - Intel Iris: 12:35:12 INFO - Chipset Model: Intel Iris 12:35:12 INFO - Type: GPU 12:35:12 INFO - Bus: Built-In 12:35:12 INFO - VRAM (Dynamic, Max): 1536 MB 12:35:12 INFO - Vendor: Intel (0x8086) 12:35:12 INFO - Device ID: 0x0a2e 12:35:12 INFO - Revision ID: 0x0009 12:35:12 INFO - Displays: 12:35:12 INFO - Display: 12:35:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:35:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:35:12 INFO - Display Serial Number: 1600x1200 60 12:35:12 INFO - Main Display: Yes 12:35:12 INFO - Mirror: Off 12:35:12 INFO - Online: Yes 12:35:12 INFO - Rotation: Supported 12:35:12 INFO - Return code: 0 12:35:12 INFO - Running post-action listener: _resource_record_post_action 12:35:12 INFO - [mozharness: 2017-05-18 19:35:12.155094Z] Finished run-tests step (success) 12:35:12 INFO - Running post-run listener: _resource_record_post_run 12:35:12 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 40384512; Write bytes: 424685568; Read time: 390; Write time: 1663 12:35:12 INFO - TinderboxPrint: CPU usage
54.5% 12:35:12 INFO - TinderboxPrint: I/O read bytes / time
40,384,512 / 390 12:35:12 INFO - TinderboxPrint: I/O write bytes / time
424,685,568 / 1,663 12:35:12 INFO - TinderboxPrint: CPU idle
2,172.6 (45.5%) 12:35:12 INFO - TinderboxPrint: CPU system
958.3 (20.1%) 12:35:12 INFO - TinderboxPrint: CPU user
1,642.8 (34.4%) 12:35:12 INFO - TinderboxPrint: Swap in / out
162,574,336 / 0 12:35:12 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121655808; Write bytes: 114024448; Read time: 11076; Write time: 253 12:35:12 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35809280; Write bytes: 300515328; Read time: 358; Write time: 1376 12:35:12 INFO - Running post-run listener: _upload_blobber_files 12:35:12 INFO - Blob upload gear active. 12:35:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:35:12 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 12:35:12 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'] 12:35:12 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 12:35:13 INFO - (blobuploader) - INFO - Open directory for files ... 12:35:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:35:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:35:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:35:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:35:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:35:14 INFO - (blobuploader) - INFO - Done attempting. 12:35:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:35:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:35:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:35:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:35:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:35:15 INFO - (blobuploader) - INFO - Done attempting. 12:35:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:35:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:35:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:35:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:35:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:35:16 INFO - (blobuploader) - INFO - Done attempting. 12:35:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:35:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:35:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:35:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:35:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:35:18 INFO - (blobuploader) - INFO - Done attempting. 12:35:18 INFO - (blobuploader) - INFO - Iteration through files over. 12:35:18 INFO - Return code: 0 12:35:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:35:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:35:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f1fd6c953f41179ed92b56fe880a4a95249a30f2a3693a1ffbaa57d3a04964a87a341fa3aa610907e8f10ec46df976efa77b2b9a2a1e9da460643e6603106fd2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94d4acece0fa9e58497caf0af49d9a1eb788194ee8234963a466fc33a2d5be23835ab0abfc197f66b1df833db19a106d8366a557c5cb1c8ce80814a442cbd296", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/afbcdb69f253fc8f522b076c8d187b4ddd514ec323456b6201b73617aca5fd24467f03f93f0694c446bdfed6bdac3f41edcdf3bbfa7abd7e5679494951c1fa1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b15d3d6535d08e3e9cc1d4a339c43bfbc9f99b9c4e822bde1261e0219af15532326a209c474e98af95056dea08266a2707287bd3acaa607ea2031857e866f940"} 12:35:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:35:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:35:18 INFO - Contents: 12:35:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f1fd6c953f41179ed92b56fe880a4a95249a30f2a3693a1ffbaa57d3a04964a87a341fa3aa610907e8f10ec46df976efa77b2b9a2a1e9da460643e6603106fd2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94d4acece0fa9e58497caf0af49d9a1eb788194ee8234963a466fc33a2d5be23835ab0abfc197f66b1df833db19a106d8366a557c5cb1c8ce80814a442cbd296", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/afbcdb69f253fc8f522b076c8d187b4ddd514ec323456b6201b73617aca5fd24467f03f93f0694c446bdfed6bdac3f41edcdf3bbfa7abd7e5679494951c1fa1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b15d3d6535d08e3e9cc1d4a339c43bfbc9f99b9c4e822bde1261e0219af15532326a209c474e98af95056dea08266a2707287bd3acaa607ea2031857e866f940"} 12:35:18 INFO - Running post-run listener: copy_logs_to_upload_dir 12:35:18 INFO - Copying logs to upload dir... 12:35:18 INFO - mkdir: /builds/slave/test/build/upload/logs 12:35:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.553988 ========= master_lag: 3.81 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-05-18 12:35:22.613692) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 9 secs) (at 2017-05-18 12:35:22.616778) ========= 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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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/f1fd6c953f41179ed92b56fe880a4a95249a30f2a3693a1ffbaa57d3a04964a87a341fa3aa610907e8f10ec46df976efa77b2b9a2a1e9da460643e6603106fd2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94d4acece0fa9e58497caf0af49d9a1eb788194ee8234963a466fc33a2d5be23835ab0abfc197f66b1df833db19a106d8366a557c5cb1c8ce80814a442cbd296", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/afbcdb69f253fc8f522b076c8d187b4ddd514ec323456b6201b73617aca5fd24467f03f93f0694c446bdfed6bdac3f41edcdf3bbfa7abd7e5679494951c1fa1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b15d3d6535d08e3e9cc1d4a339c43bfbc9f99b9c4e822bde1261e0219af15532326a209c474e98af95056dea08266a2707287bd3acaa607ea2031857e866f940"} build_url:https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011347 build_url: 'https://queue.taskcluster.net/v1/task/fJatWkpTRjWfcgEFoFPcCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f1fd6c953f41179ed92b56fe880a4a95249a30f2a3693a1ffbaa57d3a04964a87a341fa3aa610907e8f10ec46df976efa77b2b9a2a1e9da460643e6603106fd2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94d4acece0fa9e58497caf0af49d9a1eb788194ee8234963a466fc33a2d5be23835ab0abfc197f66b1df833db19a106d8366a557c5cb1c8ce80814a442cbd296", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/afbcdb69f253fc8f522b076c8d187b4ddd514ec323456b6201b73617aca5fd24467f03f93f0694c446bdfed6bdac3f41edcdf3bbfa7abd7e5679494951c1fa1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b15d3d6535d08e3e9cc1d4a339c43bfbc9f99b9c4e822bde1261e0219af15532326a209c474e98af95056dea08266a2707287bd3acaa607ea2031857e866f940"}' ========= master_lag: 9.66 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 9 secs) (at 2017-05-18 12:35:32.283318) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 12:35:32.283680) ========= 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.bNSL9wiSPK/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.KUCi4VAi7q/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.005327 ========= master_lag: 0.61 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 12:35:32.895088) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 12:35:32.895446) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 12:35:33.063754) ========= ========= Total master_lag: 14.15 =========