builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0352 starttime: 1495502582.31 results: success (0) buildid: 20170522172523 builduid: 9c9ded593bfd415392f526a295a2f702 revision: a6edfa473c7dde50edc14a85374eb400e5ada494 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:02.309452) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:02.310474) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:02.311150) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:02.338593) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:02.339203) ========= 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.SkFthDlezz/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.nXdDxA5ZCn/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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-22 18:23:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2017-05-22 18:23:02 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138369 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:02.503374) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:02.503711) ========= 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.SkFthDlezz/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.nXdDxA5ZCn/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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.037442 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:02.561621) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 18:23:02.561984) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a6edfa473c7dde50edc14a85374eb400e5ada494 --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 a6edfa473c7dde50edc14a85374eb400e5ada494 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SkFthDlezz/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.nXdDxA5ZCn/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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-22 18:23:02,659 truncating revision to first 12 chars 2017-05-22 18:23:02,659 Setting DEBUG logging. 2017-05-22 18:23:02,659 attempt 1/10 2017-05-22 18:23:02,659 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a6edfa473c7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 18:23:04,155 unpacking tar archive at: mozilla-beta-a6edfa473c7d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.775300 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 18:23:04.359073) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:04.359434) ========= script_repo_revision: a6edfa473c7dde50edc14a85374eb400e5ada494 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:04.359865) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:04.360219) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:04.378439) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-22 18:23:04.378745) ========= /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.SkFthDlezz/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.nXdDxA5ZCn/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 18:23:04 INFO - MultiFileLogger online at 20170522 18:23:04 in /builds/slave/test 18:23:04 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 18:23:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:23:04 INFO - {'append_to_log': False, 18:23:04 INFO - 'base_work_dir': '/builds/slave/test', 18:23:04 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:23:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:23:04 INFO - 'branch': 'Mozilla-Beta', 18:23:04 INFO - 'buildbot_json_path': 'buildprops.json', 18:23:04 INFO - 'config_files': ('talos/mac_config.py',), 18:23:04 INFO - 'default_actions': ('clobber', 18:23:04 INFO - 'read-buildbot-config', 18:23:04 INFO - 'download-and-extract', 18:23:04 INFO - 'populate-webroot', 18:23:04 INFO - 'create-virtualenv', 18:23:04 INFO - 'install', 18:23:04 INFO - 'run-tests'), 18:23:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:23:04 INFO - 'download_minidump_stackwalk': True, 18:23:04 INFO - 'download_symbols': 'ondemand', 18:23:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:23:04 INFO - 'tooltool.py': '/tools/tooltool.py', 18:23:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:23:04 INFO - '/tools/misc-python/virtualenv.py')}, 18:23:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:23:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:23:04 INFO - 'gecko_profile': False, 18:23:04 INFO - 'gecko_profile_interval': 0, 18:23:04 INFO - 'installer_path': 'installer.exe', 18:23:04 INFO - 'log_level': 'info', 18:23:04 INFO - 'log_name': 'talos', 18:23:04 INFO - 'log_to_console': True, 18:23:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:23:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:23:04 INFO - 'opt_config_files': (), 18:23:04 INFO - 'pip_index': False, 18:23:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:23:04 INFO - 'cmd': ('bash', 18:23:04 INFO - '-c', 18:23:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:23:04 INFO - 'enabled': True, 18:23:04 INFO - 'halt_on_failure': False, 18:23:04 INFO - 'name': 'check_screen_resolution'},), 18:23:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:23:04 INFO - 'cmd': ('bash', 18:23:04 INFO - '-c', 18:23:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:23:04 INFO - 'enabled': True, 18:23:04 INFO - 'halt_on_failure': False, 18:23:04 INFO - 'name': 'check_screen_resolution'},), 18:23:04 INFO - 'run_cmd_checks_enabled': True, 18:23:04 INFO - 'suite': 'dromaeojs-e10s', 18:23:04 INFO - 'system_bits': '32', 18:23:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:23:04 INFO - 'title': 't-yosemite-r7-0352', 18:23:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:23:04 INFO - 'use_talos_json': True, 18:23:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:23:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:23:04 INFO - 'work_dir': 'build'} 18:23:04 INFO - [mozharness: 2017-05-23 01:23:04.583610Z] Running clobber step. 18:23:04 INFO - Running pre-action listener: _resource_record_pre_action 18:23:04 INFO - Running main action method: clobber 18:23:04 INFO - rmtree: /builds/slave/test/build 18:23:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:23:07 INFO - Running post-action listener: _resource_record_post_action 18:23:07 INFO - [mozharness: 2017-05-23 01:23:07.223929Z] Finished clobber step (success) 18:23:07 INFO - [mozharness: 2017-05-23 01:23:07.224056Z] Running read-buildbot-config step. 18:23:07 INFO - Running pre-action listener: _resource_record_pre_action 18:23:07 INFO - Running main action method: read_buildbot_config 18:23:07 INFO - Using buildbot properties: 18:23:07 INFO - { 18:23:07 INFO - "project": "", 18:23:07 INFO - "product": "firefox", 18:23:07 INFO - "script_repo_revision": "production", 18:23:07 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 18:23:07 INFO - "repository": "", 18:23:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 18:23:07 INFO - "buildid": "20170522172523", 18:23:07 INFO - "pgo_build": "False", 18:23:07 INFO - "basedir": "/builds/slave/test", 18:23:07 INFO - "buildnumber": 4, 18:23:07 INFO - "slavename": "t-yosemite-r7-0352", 18:23:07 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:23:07 INFO - "platform": "yosemite_r7", 18:23:07 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 18:23:07 INFO - "branch": "mozilla-beta", 18:23:07 INFO - "repo_path": "releases/mozilla-beta", 18:23:07 INFO - "revision": "a6edfa473c7dde50edc14a85374eb400e5ada494", 18:23:07 INFO - "stage_platform": "macosx64", 18:23:07 INFO - "builduid": "9c9ded593bfd415392f526a295a2f702", 18:23:07 INFO - "slavebuilddir": "test" 18:23:07 INFO - } 18:23:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 18:23:07 INFO - Running post-action listener: _resource_record_post_action 18:23:07 INFO - [mozharness: 2017-05-23 01:23:07.227461Z] Finished read-buildbot-config step (success) 18:23:07 INFO - [mozharness: 2017-05-23 01:23:07.227570Z] Running download-and-extract step. 18:23:07 INFO - Running pre-action listener: _resource_record_pre_action 18:23:07 INFO - Running main action method: download_and_extract 18:23:07 INFO - mkdir: /builds/slave/test/build/tests 18:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:07 INFO - trying https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 18:23:07 INFO - Downloading https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/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 18:23:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/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 18:23:08 INFO - Downloaded 1287 bytes. 18:23:08 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 18:23:08 INFO - Using the following test package requirements: 18:23:08 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:08 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 18:23:08 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 18:23:08 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:08 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 18:23:08 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:08 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 18:23:08 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 18:23:08 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:08 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 18:23:08 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 18:23:08 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:08 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 18:23:08 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:08 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 18:23:08 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:08 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 18:23:08 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:08 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 18:23:08 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 18:23:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 18:23:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 18:23:08 INFO - Fetch https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 18:23:09 INFO - Expected file size: 51396036 18:23:09 INFO - Obtained file size: 51396036 18:23:26 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 18:23:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 18:23:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 18:23:26 INFO - Fetch https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 18:23:28 INFO - Expected file size: 51396036 18:23:28 INFO - Obtained file size: 51396036 18:23:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 18:23:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 18:23:44 INFO - Fetch https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 18:23:45 INFO - Expected file size: 14404449 18:23:45 INFO - Obtained file size: 14404449 18:23:45 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')]} 18:23:45 INFO - trying https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 18:23:45 INFO - Downloading https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 18:23:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/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 18:23:47 INFO - Downloaded 59650219 bytes. 18:23:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 18:23:47 INFO - mkdir: /builds/slave/test/properties 18:23:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:23:47 INFO - Writing to file /builds/slave/test/properties/build_url 18:23:47 INFO - Contents: 18:23:47 INFO - build_url:https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 18:23:48 INFO - Running post-action listener: _resource_record_post_action 18:23:48 INFO - Running post-action listener: set_extra_try_arguments 18:23:48 INFO - [mozharness: 2017-05-23 01:23:48.180038Z] Finished download-and-extract step (success) 18:23:48 INFO - [mozharness: 2017-05-23 01:23:48.180185Z] Running populate-webroot step. 18:23:48 INFO - Running pre-action listener: _resource_record_pre_action 18:23:48 INFO - Running main action method: populate_webroot 18:23:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:23:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:23:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:23:48 INFO - '--tppagecycles', 18:23:48 INFO - '7'], 18:23:48 INFO - 'tests': ['tsvgm']}}, 18:23:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:23:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:23:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:23:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:23:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:23:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:23:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:23:48 INFO - 'talos_options': ['--disable-e10s'], 18:23:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:23:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:23:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:23:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:23:48 INFO - 'talos_options': ['--disable-e10s'], 18:23:48 INFO - 'tests': ['damp', 'tps']}, 18:23:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:23:48 INFO - 'tests': ['damp', 'tps']}, 18:23:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:23:48 INFO - 'tests': ['dromaeo_dom']}, 18:23:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:23:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:23:48 INFO - 'tests': ['basic_compositor_video']}, 18:23:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:23:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:23:48 INFO - 'tests': ['a11yr', 18:23:48 INFO - 'ts_paint', 18:23:48 INFO - 'tpaint', 18:23:48 INFO - 'sessionrestore', 18:23:48 INFO - 'sessionrestore_no_auto_restore', 18:23:48 INFO - 'tabpaint']}, 18:23:48 INFO - 'other-e10s': {'tests': ['a11yr', 18:23:48 INFO - 'ts_paint', 18:23:48 INFO - 'tpaint', 18:23:48 INFO - 'sessionrestore', 18:23:48 INFO - 'sessionrestore_no_auto_restore', 18:23:48 INFO - 'tabpaint']}, 18:23:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:23:48 INFO - 'ts_paint', 18:23:48 INFO - 'tpaint', 18:23:48 INFO - 'sessionrestore', 18:23:48 INFO - 'sessionrestore_no_auto_restore', 18:23:48 INFO - 'tabpaint']}, 18:23:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:23:48 INFO - 'ts_paint', 18:23:48 INFO - 'tpaint', 18:23:48 INFO - 'sessionrestore', 18:23:48 INFO - 'sessionrestore_no_auto_restore', 18:23:48 INFO - 'tabpaint']}, 18:23:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:23:48 INFO - 'tests': ['a11yr', 18:23:48 INFO - 'ts_paint', 18:23:48 INFO - 'tpaint', 18:23:48 INFO - 'sessionrestore', 18:23:48 INFO - 'sessionrestore_no_auto_restore', 18:23:48 INFO - 'tabpaint']}, 18:23:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:23:48 INFO - 'tests': ['a11yr', 18:23:48 INFO - 'ts_paint', 18:23:48 INFO - 'tpaint', 18:23:48 INFO - 'sessionrestore', 18:23:48 INFO - 'sessionrestore_no_auto_restore', 18:23:48 INFO - 'tabpaint']}, 18:23:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:23:48 INFO - 'tests': ['tsvgx', 18:23:48 INFO - 'tsvgr_opacity', 18:23:48 INFO - 'tart', 18:23:48 INFO - 'tscrollx', 18:23:48 INFO - 'cart', 18:23:48 INFO - 'tsvg_static']}, 18:23:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:23:48 INFO - 'tsvgr_opacity', 18:23:48 INFO - 'tart', 18:23:48 INFO - 'tscrollx', 18:23:48 INFO - 'cart', 18:23:48 INFO - 'tsvg_static']}, 18:23:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:23:48 INFO - 'talos_options': ['--disable-e10s'], 18:23:48 INFO - 'tests': ['tp5o']}, 18:23:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:23:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:23:48 INFO - 'talos_options': ['--disable-e10s', 18:23:48 INFO - '--xperf_path', 18:23:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:23:48 INFO - 'tests': ['tp5n']}, 18:23:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:23:48 INFO - 'talos_options': ['--xperf_path', 18:23:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:23:48 INFO - 'tests': ['tp5n']}}, 18:23:48 INFO - 'talos.zip': {'path': '', 18:23:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:23:48 INFO - Running post-action listener: _resource_record_post_action 18:23:48 INFO - [mozharness: 2017-05-23 01:23:48.200276Z] Finished populate-webroot step (success) 18:23:48 INFO - [mozharness: 2017-05-23 01:23:48.200419Z] Running create-virtualenv step. 18:23:48 INFO - Running pre-action listener: _resource_record_pre_action 18:23:48 INFO - Running main action method: create_virtualenv 18:23:48 INFO - Creating virtualenv /builds/slave/test/build/venv 18:23:48 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 18:23:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:23:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:23:48 INFO - Using real prefix '/tools/python27' 18:23:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:23:49 INFO - Installing distribute.............................................................................................................................................................................................done. 18:23:51 INFO - Installing pip.................done. 18:23:51 INFO - Return code: 0 18:23:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:23:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:23:52 INFO - Reading from file tmpfile_stdout 18:23:52 INFO - Output received: 18:23:52 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) 18:23:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:23:52 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')]} 18:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:52 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')]} 18:23:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:52 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 0x101d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff180c82b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b73d30>, '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 0x101a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b68be8>, '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.SkFthDlezz/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.nXdDxA5ZCn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 18:23:52 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 18:23:52 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 18:23:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SkFthDlezz/Render', 18:23:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:23:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:23:52 INFO - 'HOME': '/Users/cltbld', 18:23:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:23:52 INFO - 'LOGNAME': 'cltbld', 18:23:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:52 INFO - 'MOZ_NO_REMOTE': '1', 18:23:52 INFO - 'NO_EM_RESTART': '1', 18:23:52 INFO - 'PAGER': '/bin/cat', 18:23:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:23:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:52 INFO - 'PWD': '/builds/slave/test', 18:23:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:23:52 INFO - 'SHELL': '/bin/bash', 18:23:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nXdDxA5ZCn/Listeners', 18:23:52 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 18:23:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:23:52 INFO - 'USER': 'cltbld', 18:23:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:23:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:23:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:52 INFO - 'XPC_FLAGS': '0x0', 18:23:52 INFO - 'XPC_SERVICE_NAME': '0', 18:23:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:52 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 18:23:52 INFO - Cleaning up... 18:23:52 INFO - Return code: 0 18:23:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:23:52 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')]} 18:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:52 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')]} 18:23:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:52 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 0x101d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff180c82b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b73d30>, '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 0x101a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b68be8>, '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.SkFthDlezz/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.nXdDxA5ZCn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 18:23:52 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 18:23:52 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 18:23:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SkFthDlezz/Render', 18:23:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:23:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:23:52 INFO - 'HOME': '/Users/cltbld', 18:23:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:23:52 INFO - 'LOGNAME': 'cltbld', 18:23:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:52 INFO - 'MOZ_NO_REMOTE': '1', 18:23:52 INFO - 'NO_EM_RESTART': '1', 18:23:52 INFO - 'PAGER': '/bin/cat', 18:23:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:23:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:52 INFO - 'PWD': '/builds/slave/test', 18:23:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:23:52 INFO - 'SHELL': '/bin/bash', 18:23:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nXdDxA5ZCn/Listeners', 18:23:52 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 18:23:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:23:52 INFO - 'USER': 'cltbld', 18:23:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:23:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:23:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:52 INFO - 'XPC_FLAGS': '0x0', 18:23:52 INFO - 'XPC_SERVICE_NAME': '0', 18:23:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:52 INFO - Downloading/unpacking psutil>=3.1.1 18:23:52 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 18:23:52 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 18:23:52 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 18:23:52 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 18:23:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:23:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:23:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:23:54 INFO - Installing collected packages: psutil 18:23:54 INFO - Running setup.py install for psutil 18:23:54 INFO - building 'psutil._psutil_osx' extension 18:23:54 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 18:23:55 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 18:23:55 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 18:23:55 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 18:23:55 INFO - building 'psutil._psutil_posix' extension 18:23:55 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 18:23:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:23:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:23:55 INFO - ^ 18:23:55 INFO - 1 warning generated. 18:23:55 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 18:23:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:23:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:23:55 INFO - Successfully installed psutil 18:23:55 INFO - Cleaning up... 18:23:55 INFO - Return code: 0 18:23:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:23:55 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')]} 18:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:55 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')]} 18:23:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:55 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 0x101d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff180c82b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b73d30>, '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 0x101a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b68be8>, '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.SkFthDlezz/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.nXdDxA5ZCn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 18:23:55 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 18:23:55 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 18:23:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SkFthDlezz/Render', 18:23:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:23:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:23:55 INFO - 'HOME': '/Users/cltbld', 18:23:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:23:55 INFO - 'LOGNAME': 'cltbld', 18:23:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:55 INFO - 'MOZ_NO_REMOTE': '1', 18:23:55 INFO - 'NO_EM_RESTART': '1', 18:23:55 INFO - 'PAGER': '/bin/cat', 18:23:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:23:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:55 INFO - 'PWD': '/builds/slave/test', 18:23:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:23:55 INFO - 'SHELL': '/bin/bash', 18:23:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nXdDxA5ZCn/Listeners', 18:23:55 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 18:23:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:23:55 INFO - 'USER': 'cltbld', 18:23:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:23:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:23:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:55 INFO - 'XPC_FLAGS': '0x0', 18:23:55 INFO - 'XPC_SERVICE_NAME': '0', 18:23:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:23:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:23:56 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 18:23:56 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 18:23:56 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 18:23:56 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 18:23:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:23:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:23:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:23:57 INFO - Installing collected packages: mozsystemmonitor 18:23:57 INFO - Running setup.py install for mozsystemmonitor 18:23:57 INFO - Successfully installed mozsystemmonitor 18:23:57 INFO - Cleaning up... 18:23:57 INFO - Return code: 0 18:23:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:57 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 0x101d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff180c82b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b73d30>, '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 0x101a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b68be8>, '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.SkFthDlezz/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.nXdDxA5ZCn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 18:23:57 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 18:23:57 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 18:23:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SkFthDlezz/Render', 18:23:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:23:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:23:57 INFO - 'HOME': '/Users/cltbld', 18:23:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:23:57 INFO - 'LOGNAME': 'cltbld', 18:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:57 INFO - 'MOZ_NO_REMOTE': '1', 18:23:57 INFO - 'NO_EM_RESTART': '1', 18:23:57 INFO - 'PAGER': '/bin/cat', 18:23:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:57 INFO - 'PWD': '/builds/slave/test', 18:23:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:23:57 INFO - 'SHELL': '/bin/bash', 18:23:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nXdDxA5ZCn/Listeners', 18:23:57 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 18:23:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:23:57 INFO - 'USER': 'cltbld', 18:23:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:23:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:57 INFO - 'XPC_FLAGS': '0x0', 18:23:57 INFO - 'XPC_SERVICE_NAME': '0', 18:23:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:57 INFO - Downloading/unpacking jsonschema==2.5.1 18:23: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 18:23: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 18:23: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 18:23: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 18:23:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:23:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:23:59 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 18:23:59 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 18:23:59 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 18:23:59 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 18:23:59 INFO - Downloading functools32-3.2.3-2.tar.gz 18:23:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:24:00 INFO - warning: no files found matching '*.txt' 18:24:00 INFO - no previously-included directories found matching 'build' 18:24:00 INFO - no previously-included directories found matching 'dist' 18:24:00 INFO - no previously-included directories found matching '.git*' 18:24:00 INFO - Installing collected packages: jsonschema, functools32 18:24:00 INFO - Running setup.py install for functools32 18:24:00 INFO - warning: no files found matching '*.txt' 18:24:00 INFO - no previously-included directories found matching 'build' 18:24:00 INFO - no previously-included directories found matching 'dist' 18:24:00 INFO - no previously-included directories found matching '.git*' 18:24:00 INFO - Successfully installed jsonschema functools32 18:24:00 INFO - Cleaning up... 18:24:00 INFO - Return code: 0 18:24:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:24: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')]} 18:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24: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')]} 18:24:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24: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', '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 0x101d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff180c82b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b73d30>, '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 0x101a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b68be8>, '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.SkFthDlezz/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.nXdDxA5ZCn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 18:24: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:24: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 functools32==3.2.3-2 18:24:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SkFthDlezz/Render', 18:24:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:00 INFO - 'HOME': '/Users/cltbld', 18:24:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:00 INFO - 'LOGNAME': 'cltbld', 18:24:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:00 INFO - 'MOZ_NO_REMOTE': '1', 18:24:00 INFO - 'NO_EM_RESTART': '1', 18:24:00 INFO - 'PAGER': '/bin/cat', 18:24:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:00 INFO - 'PWD': '/builds/slave/test', 18:24:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:00 INFO - 'SHELL': '/bin/bash', 18:24:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nXdDxA5ZCn/Listeners', 18:24:00 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 18:24:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:00 INFO - 'USER': 'cltbld', 18:24:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:00 INFO - 'XPC_FLAGS': '0x0', 18:24:00 INFO - 'XPC_SERVICE_NAME': '0', 18:24:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:24:00 INFO - Cleaning up... 18:24:00 INFO - Return code: 0 18:24:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:24: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')]} 18:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24: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')]} 18:24:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24: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', '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 0x101d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff180c82b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b73d30>, '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 0x101a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b68be8>, '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.SkFthDlezz/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.nXdDxA5ZCn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 18:24: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:24: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 blobuploader==1.2.4 18:24:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SkFthDlezz/Render', 18:24:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:00 INFO - 'HOME': '/Users/cltbld', 18:24:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:00 INFO - 'LOGNAME': 'cltbld', 18:24:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:00 INFO - 'MOZ_NO_REMOTE': '1', 18:24:00 INFO - 'NO_EM_RESTART': '1', 18:24:00 INFO - 'PAGER': '/bin/cat', 18:24:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:00 INFO - 'PWD': '/builds/slave/test', 18:24:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:00 INFO - 'SHELL': '/bin/bash', 18:24:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nXdDxA5ZCn/Listeners', 18:24:00 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 18:24:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:00 INFO - 'USER': 'cltbld', 18:24:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:00 INFO - 'XPC_FLAGS': '0x0', 18:24:00 INFO - 'XPC_SERVICE_NAME': '0', 18:24:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:01 INFO - Downloading/unpacking blobuploader==1.2.4 18:24:01 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 18:24:01 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 18:24:01 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 18:24:01 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 18:24:02 INFO - Downloading blobuploader-1.2.4.tar.gz 18:24:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:24:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:24: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 18:24: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 18:24: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 18:24: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 18:24:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:24:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:24: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 18:24: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 18:24: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 18:24: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 18:24:03 INFO - Downloading docopt-0.6.1.tar.gz 18:24:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:24:03 INFO - Installing collected packages: blobuploader, requests, docopt 18:24:03 INFO - Running setup.py install for blobuploader 18:24:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:24:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:24:03 INFO - Running setup.py install for requests 18:24:04 INFO - Running setup.py install for docopt 18:24:04 INFO - Successfully installed blobuploader requests docopt 18:24:04 INFO - Cleaning up... 18:24:04 INFO - Return code: 0 18:24:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24: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')]} 18:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24: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')]} 18:24:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:04 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 0x101d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff180c82b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b73d30>, '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 0x101a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b68be8>, '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.SkFthDlezz/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.nXdDxA5ZCn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 18:24:04 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 18:24:04 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 18:24:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SkFthDlezz/Render', 18:24:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:04 INFO - 'HOME': '/Users/cltbld', 18:24:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:04 INFO - 'LOGNAME': 'cltbld', 18:24:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:04 INFO - 'MOZ_NO_REMOTE': '1', 18:24:04 INFO - 'NO_EM_RESTART': '1', 18:24:04 INFO - 'PAGER': '/bin/cat', 18:24:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:04 INFO - 'PWD': '/builds/slave/test', 18:24:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:04 INFO - 'SHELL': '/bin/bash', 18:24:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nXdDxA5ZCn/Listeners', 18:24:04 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 18:24:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:04 INFO - 'USER': 'cltbld', 18:24:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:04 INFO - 'XPC_FLAGS': '0x0', 18:24:04 INFO - 'XPC_SERVICE_NAME': '0', 18:24:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:04 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-Tc8Aj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:04 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-Gs9HVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:04 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-KHL7Cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:05 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-PU1i_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:05 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-fOgF9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:05 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-2RZTGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:05 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-oSR2Lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:05 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-DLwys_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:05 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-hXVg8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:05 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-NXCHGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:05 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-g2RNLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:05 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-AbNIWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:05 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-Jd7RaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:06 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-QwfXvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:06 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-UpXvCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:06 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-VIQdQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:06 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-sM2P1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:24:06 INFO - Running setup.py install for manifestparser 18:24:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:24:06 INFO - Running setup.py install for mozcrash 18:24:06 INFO - Running setup.py install for mozdebug 18:24:06 INFO - Running setup.py install for mozdevice 18:24:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:24:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:24:07 INFO - Running setup.py install for mozfile 18:24:07 INFO - Running setup.py install for mozhttpd 18:24:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:24:07 INFO - Running setup.py install for mozinfo 18:24:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:24:07 INFO - Running setup.py install for mozInstall 18:24:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:24:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:24:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:24:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:24:07 INFO - Running setup.py install for mozleak 18:24:07 INFO - Running setup.py install for mozlog 18:24:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:24:08 INFO - Running setup.py install for moznetwork 18:24:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:24:08 INFO - Running setup.py install for mozprocess 18:24:08 INFO - Running setup.py install for mozprofile 18:24:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:24:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:24:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:24:08 INFO - Running setup.py install for mozrunner 18:24:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:24:08 INFO - Running setup.py install for mozscreenshot 18:24:08 INFO - Running setup.py install for moztest 18:24:08 INFO - Running setup.py install for mozversion 18:24:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:24:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:24:09 INFO - Cleaning up... 18:24:09 INFO - Return code: 0 18:24:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:09 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 0x101d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff180c82b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b73d30>, '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 0x101a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b68be8>, '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.SkFthDlezz/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.nXdDxA5ZCn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 18:24:09 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 18:24:09 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 18:24:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SkFthDlezz/Render', 18:24:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:09 INFO - 'HOME': '/Users/cltbld', 18:24:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:09 INFO - 'LOGNAME': 'cltbld', 18:24:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:09 INFO - 'MOZ_NO_REMOTE': '1', 18:24:09 INFO - 'NO_EM_RESTART': '1', 18:24:09 INFO - 'PAGER': '/bin/cat', 18:24:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:09 INFO - 'PWD': '/builds/slave/test', 18:24:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:09 INFO - 'SHELL': '/bin/bash', 18:24:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nXdDxA5ZCn/Listeners', 18:24:09 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 18:24:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:09 INFO - 'USER': 'cltbld', 18:24:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:09 INFO - 'XPC_FLAGS': '0x0', 18:24:09 INFO - 'XPC_SERVICE_NAME': '0', 18:24:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:09 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-OySyyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:09 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)) 18:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:09 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-jfUUZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:09 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)) 18:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:09 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-iIaWY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:09 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)) 18:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:09 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-rO0UUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:09 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)) 18:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:09 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-4gevQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:09 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)) 18:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:09 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-i8glP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:10 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-kGapGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:10 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-shrnGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:10 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-NIlPEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:10 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-ylM1Ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:10 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-quHK2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:10 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-XdN0vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:10 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-klYZ2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:10 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-D8jCuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:10 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-CJi_XK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:10 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-nkJHtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:11 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)) 18:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:11 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-W9Sogt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:11 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)) 18:24:11 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)) 18:24:11 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)) 18:24:11 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)) 18:24:11 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)) 18:24:11 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)) 18:24:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:24:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:12 INFO - Downloading blessings-1.6.tar.gz 18:24:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:24:12 INFO - Installing collected packages: blessings 18:24:12 INFO - Running setup.py install for blessings 18:24:12 INFO - Successfully installed blessings 18:24:12 INFO - Cleaning up... 18:24:12 INFO - Return code: 0 18:24:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:24:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:13 INFO - Reading from file tmpfile_stdout 18:24:13 INFO - Current package versions: 18:24:13 INFO - blessings == 1.6 18:24:13 INFO - blobuploader == 1.2.4 18:24:13 INFO - docopt == 0.6.1 18:24:13 INFO - functools32 == 3.2.3-2 18:24:13 INFO - jsonschema == 2.5.1 18:24:13 INFO - manifestparser == 1.1 18:24:13 INFO - mozInstall == 1.12 18:24:13 INFO - mozcrash == 1.0 18:24:13 INFO - mozdebug == 0.1 18:24:13 INFO - mozdevice == 0.49 18:24:13 INFO - mozfile == 1.2 18:24:13 INFO - mozhttpd == 0.7 18:24:13 INFO - mozinfo == 0.9 18:24:13 INFO - mozleak == 0.1 18:24:13 INFO - mozlog == 3.4 18:24:13 INFO - moznetwork == 0.27 18:24:13 INFO - mozprocess == 0.25 18:24:13 INFO - mozprofile == 0.28 18:24:13 INFO - mozrunner == 6.13 18:24:13 INFO - mozscreenshot == 0.1 18:24:13 INFO - mozsystemmonitor == 0.3 18:24:13 INFO - moztest == 0.8 18:24:13 INFO - mozversion == 1.4 18:24:13 INFO - psutil == 3.1.1 18:24:13 INFO - requests == 1.2.3 18:24:13 INFO - wsgiref == 0.1.2 18:24:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24: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')]} 18:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24: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')]} 18:24:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:13 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 0x101d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff180c82b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b73d30>, '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 0x101a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b68be8>, '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.SkFthDlezz/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.nXdDxA5ZCn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 18:24:13 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 18:24:13 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 18:24:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SkFthDlezz/Render', 18:24:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:13 INFO - 'HOME': '/Users/cltbld', 18:24:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:13 INFO - 'LOGNAME': 'cltbld', 18:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:13 INFO - 'MOZ_NO_REMOTE': '1', 18:24:13 INFO - 'NO_EM_RESTART': '1', 18:24:13 INFO - 'PAGER': '/bin/cat', 18:24:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:13 INFO - 'PWD': '/builds/slave/test', 18:24:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:13 INFO - 'SHELL': '/bin/bash', 18:24:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nXdDxA5ZCn/Listeners', 18:24:13 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 18:24:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:13 INFO - 'USER': 'cltbld', 18:24:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:13 INFO - 'XPC_FLAGS': '0x0', 18:24:13 INFO - 'XPC_SERVICE_NAME': '0', 18:24:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 INFO - Cleaning up... 18:24:13 INFO - Return code: 0 18:24:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:24: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')]} 18:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24: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')]} 18:24:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff180c82b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b73d30>, '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 0x101a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b68be8>, '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.SkFthDlezz/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.nXdDxA5ZCn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 18:24:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:24:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:24:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SkFthDlezz/Render', 18:24:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:13 INFO - 'HOME': '/Users/cltbld', 18:24:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:13 INFO - 'LOGNAME': 'cltbld', 18:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:13 INFO - 'MOZ_NO_REMOTE': '1', 18:24:13 INFO - 'NO_EM_RESTART': '1', 18:24:13 INFO - 'PAGER': '/bin/cat', 18:24:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:13 INFO - 'PWD': '/builds/slave/test', 18:24:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:13 INFO - 'SHELL': '/bin/bash', 18:24:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nXdDxA5ZCn/Listeners', 18:24:13 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 18:24:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:13 INFO - 'USER': 'cltbld', 18:24:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:13 INFO - 'XPC_FLAGS': '0x0', 18:24:13 INFO - 'XPC_SERVICE_NAME': '0', 18:24:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:24:13 INFO - Cleaning up... 18:24:13 INFO - Return code: 0 18:24:13 INFO - Running post-action listener: _resource_record_post_action 18:24:13 INFO - Running post-action listener: _start_resource_monitoring 18:24:13 INFO - Starting resource monitoring. 18:24:13 INFO - [mozharness: 2017-05-23 01:24:13.745845Z] Finished create-virtualenv step (success) 18:24:13 INFO - [mozharness: 2017-05-23 01:24:13.746810Z] Running install step. 18:24:13 INFO - Running pre-action listener: _resource_record_pre_action 18:24:13 INFO - Running main action method: install 18:24:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:14 INFO - Reading from file tmpfile_stdout 18:24:14 INFO - Detecting whether we're running mozinstall >=1.0... 18:24:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:24:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:24:14 INFO - Reading from file tmpfile_stdout 18:24:14 INFO - Output received: 18:24:14 INFO - Usage: mozinstall [options] installer 18:24:14 INFO - Options: 18:24:14 INFO - -h, --help show this help message and exit 18:24:14 INFO - -d DEST, --destination=DEST 18:24:14 INFO - Directory to install application into. [default: 18:24:14 INFO - "/builds/slave/test"] 18:24:14 INFO - --app=APP Application being installed. [default: firefox] 18:24:14 INFO - mkdir: /builds/slave/test/build/application 18:24:14 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'] 18:24:14 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 18:24:28 INFO - Reading from file tmpfile_stdout 18:24:28 INFO - Output received: 18:24:28 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 18:24:28 INFO - Running post-action listener: _resource_record_post_action 18:24:28 INFO - [mozharness: 2017-05-23 01:24:28.982718Z] Finished install step (success) 18:24:28 INFO - [mozharness: 2017-05-23 01:24:28.982827Z] Running run-tests step. 18:24:28 INFO - Running pre-action listener: _resource_record_pre_action 18:24:28 INFO - Running main action method: run_tests 18:24:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:24:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:24:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:24:28 INFO - 2017-05-22 18:24:28.997 screenresolution[1631:7517] starting screenresolution argv=screenresolution get 18:24:29 INFO - 2017-05-22 18:24:28.999 screenresolution[1631:7517] Display 0: 1600x1200x32@60 18:24:29 INFO - 2017-05-22 18:24:29.005 screenresolution[1632:7519] starting screenresolution argv=screenresolution list 18:24:29 INFO - Available Modes on Display 0 18:24:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:24:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:24:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:24:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:24:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:24:29 INFO - Intel Iris: 18:24:29 INFO - Chipset Model: Intel Iris 18:24:29 INFO - Type: GPU 18:24:29 INFO - Bus: Built-In 18:24:29 INFO - VRAM (Dynamic, Max): 1536 MB 18:24:29 INFO - Vendor: Intel (0x8086) 18:24:29 INFO - Device ID: 0x0a2e 18:24:29 INFO - Revision ID: 0x0009 18:24:29 INFO - Displays: 18:24:29 INFO - Display: 18:24:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:24:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:24:29 INFO - Display Serial Number: 1600x1200 60 18:24:29 INFO - Main Display: Yes 18:24:29 INFO - Mirror: Off 18:24:29 INFO - Online: Yes 18:24:29 INFO - Rotation: Supported 18:24:29 INFO - Return code: 0 18:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:24:29 INFO - Python 2.7.3 18:24:29 INFO - Return code: 0 18:24:29 INFO - grabbing minidump binary from tooltool 18:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:29 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 0x101b73d30>, '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 0x101a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b68be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:24:29 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 18:24:29 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 18:24:29 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 18:24:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:24:29 INFO - Return code: 0 18:24:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:24:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:24:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:24:29 INFO - ENV: RUST_BACKTRACE is now 1 18:24:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:24:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:24:29 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/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0352', '--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 18:24:29 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/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0352 --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 18:24:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SkFthDlezz/Render', 18:24:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:29 INFO - 'HOME': '/Users/cltbld', 18:24:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:29 INFO - 'LOGNAME': 'cltbld', 18:24:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:24:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:29 INFO - 'MOZ_NO_REMOTE': '1', 18:24:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:24:29 INFO - 'NO_EM_RESTART': '1', 18:24:29 INFO - 'PAGER': '/bin/cat', 18:24:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:29 INFO - 'PWD': '/builds/slave/test', 18:24:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:24:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:29 INFO - 'RUST_BACKTRACE': '1', 18:24:29 INFO - 'SHELL': '/bin/bash', 18:24:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nXdDxA5ZCn/Listeners', 18:24:29 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 18:24:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:29 INFO - 'USER': 'cltbld', 18:24:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:29 INFO - 'XPC_FLAGS': '0x0', 18:24:29 INFO - 'XPC_SERVICE_NAME': '0', 18:24:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:29 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/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0352', '--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 18:24:29 INFO - mozversion application_buildid: 20170522172523 18:24:29 INFO - mozversion application_changeset: a6edfa473c7dde50edc14a85374eb400e5ada494 18:24:29 INFO - mozversion application_display_name: Firefox 18:24:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:24:29 INFO - mozversion application_name: Firefox 18:24:29 INFO - mozversion application_remotingname: firefox 18:24:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:24:29 INFO - mozversion application_vendor: Mozilla 18:24:29 INFO - mozversion application_version: 54.0 18:24:29 INFO - mozversion platform_buildid: 20170522172523 18:24:29 INFO - mozversion platform_changeset: a6edfa473c7dde50edc14a85374eb400e5ada494 18:24:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:24:29 INFO - mozversion platform_version: 54.0 18:24:29 INFO - using testdate: 1495502669 18:24:29 INFO - actual date: 1495502669 18:24:29 INFO - starting webserver on 'localhost:49224' 18:24:29 INFO - SUITE-START | Running 2 tests 18:24:29 INFO - TEST-START | dromaeo_css 18:24:29 INFO - operating with platform_type : mac_ 18:24:29 INFO - Initialising browser for dromaeo_css test... 18:24:29 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmp79Z0DG/profile http://localhost:49224/getInfo.html) 18:24:32 INFO - PROCESS | 1642 | 2017-05-22 18:24:32.375 plugin-container[1645:7684] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4b, name = 'com.apple.tsm.portname' 18:24:32 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:32 INFO - PROCESS | 1642 | 2017-05-22 18:24:32.375 plugin-container[1645:7684] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930f, name = 'com.apple.CFPasteboardClient' 18:24:32 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:32 INFO - PROCESS | 1642 | 2017-05-22 18:24:32.375 plugin-container[1645:7684] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:24:32 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 18:24:32 INFO - PROCESS | 1642 | colorDepth:24 18:24:32 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 18:24:32 INFO - PROCESS | 1642 | __metrics 18:24:32 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 18:24:33 INFO - TEST-INFO | 1642: exit 0 18:24:33 INFO - Browser initialized. 18:24:33 INFO - Running cycle 1/1 for dromaeo_css test... 18:24:33 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmp79Z0DG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:24:35 INFO - PROCESS | 1646 | 2017-05-22 18:24:35.488 plugin-container[1647:7822] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 18:24:35 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:35 INFO - PROCESS | 1646 | 2017-05-22 18:24:35.489 plugin-container[1647:7822] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x421b, name = 'com.apple.CFPasteboardClient' 18:24:35 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:35 INFO - PROCESS | 1646 | 2017-05-22 18:24:35.489 plugin-container[1647:7822] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:24:45 INFO - PROCESS | 1646 | RSS: Main: 174305280 18:24:45 INFO - PROCESS | 1646 | 18:27:47 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 18:27:49 INFO - PROCESS | 1646 | RSS: Main: 189181952 18:27:49 INFO - PROCESS | 1646 | 18:30:51 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 18:30:52 INFO - PROCESS | 1646 | RSS: Main: 202960896 18:30:52 INFO - PROCESS | 1646 | 18:33:54 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 18:33:55 INFO - PROCESS | 1646 | RSS: Main: 205434880 18:33:55 INFO - PROCESS | 1646 | 18:36:57 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 18:36:57 INFO - PROCESS | 1646 | RSS: Main: 208445440 18:36:57 INFO - PROCESS | 1646 | 18:40:00 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 18:40:01 INFO - PROCESS | 1646 | RSS: Main: 210984960 18:40:01 INFO - PROCESS | 1646 | 18:43:04 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 18:43:04 INFO - PROCESS | 1646 | RSS: Main: 208900096 18:43:04 INFO - PROCESS | 1646 | 18:43:04 INFO - PROCESS | 1646 | __start_tp_report 18:43:04 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 18:43:04 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 18:43:04 INFO - PROCESS | 1646 | |i|pagename|runs| 18:43:04 INFO - PROCESS | 1646 | |0;dojo.html;2989.95515067274;3203.519472079188;3633.182533929866;3656.8720094796686;3663.8534458621657;5920.526357891369;6139.2019037525115;6180.783672571461;6200.534959878005;6221.595596286238;5439.456054394558;6183.598066125699;6199;6211.56519043666;6215.316315205324;4624.97687511562;5602.60781745278;5705.25831641886;5705.885882282352;5792.826215213529;5746.109353050263;5774;5789.8263052108505;5804.82585522435;5816.883662326751;5402.486763757456;5510.779568817243;5538.916916246239;5708.1437784332475;5772.538196944234;5681.176229446728;5706.457886500776;5711.143328500717;5760.481556659921;5762.88474230513;5606.971965140148;5662.8301150965535;5675.120356344773;5688.11834165705;5752.539796816286;4657.650676199265;5043.84868453947;5427.8330159016;6104.236970378703;6219.1915051043525;5625.6905870177125;6351.142595749561;6540.509461790385;6577.309382514815;6600.273969863311;6705.798826035227;6747.865042699168;6766.357196066366;6767.932320676829;6780.525363224576;6149.723262453111;6288.968555157195;6299.905501417483;6313.494920406357;6314.242290925118;6561.507886908501;6794.626295553693;6814.182298124158;6831.555948863308;6868.656567171622;6564.638944857983;6588.275289718127;6591.472682185414;6605.669716514155;6612.404883560503;6448.838779030561;6654.6672666366485;6704.229013663394;6711.295313992058;6732.360425759545;6440.098386225932;6527.15147030883;6571.901421478682;6600.438962688226;6616.437602803816;6648.634325112069;6657.800265992028;6660.167479065117;6672.299408562031;6676.96661516699;5155.304033955443;5208.322918020709;5281.049411106038;5302.68183908959;5344.198370244494;4565.977170114128;4725.740084295397;4778.617710583145;4790.329353890458;4794.184988551885;6208.441240288395;6279;6296.622202667763;6310.990241561328;6324.114623952651;6763.188417389944;6763.458923286125;6783.830404239835;6793.762218322335;6828.2488926218075;6148.907766383508;6208;6316.905246421307;6346.714397852108;6348.682565871688;4888.95111048885;5890.028145355968;5928.407159284037;5964.956132676851;5982.371850955674;5587.804426845041;5705.71471426427;6891.310868913269;6942.895856562257;6954.687039083355;6388.520860935356;6751.864962700817;6752.527323087336;6756.45948324123;6761.222459417033;6776.051352810512;6827.590344579143;6828.453723702191;6839.589624622538;6923.688434020381;6614.6361950093205;6628.933710662832;6631.767888123893;6639.136912201383;6643.534952553275;6572;6683.030960510796;6707.66461676924;6727.831804204934;6729.899051514133;6254.6247225166635;6284.214473190851;6420.775272865428;6438.227412710505;6483.935160648333;6517.902231466626;6531.085648009377;6545.901811472927;6550.672466376757;6554.115194448879;6247.625142491465;6274.780382686584;6292.3078461368295;6390.329015453496;6398.488120950406;5786.971065144648;6379.425851673372;6431.099646049463;6561.507886908596;6564.113844631104;6334.778282760266;6383.616982981036;6406.679666016774;6420.454261387835;6422.614643121427 18:43:04 INFO - PROCESS | 1646 | |1;ext.html;754.9581577423158;826.9462484938479;851.1573542193227;871.2115535440429;988.5946761827647;38807.80596097019;41130.97172570687;41207;41364.58635413645;41377.99999999996;39807.40288895669;40002.799916002565;40050.59949400505;40089.198216035664;40123.59876401242;6960.721571137161;8436.45163064399;11194.440277986108;11578.421078946061;11771.234869733482;10370.75550933889;11361.659150225465;11482;11483.253588516762;11498;10694.946525267364;11527.020203282731;11528.769424611502;11536.365499897502;11548.076153907667;11435.028022618088;11464.598739044135;11465.369404682739;11470.254433461798;11484.770304593903;9790;11269.323840569592;11278.210525263235;11305.773884522347;11315.434228288635;35677.929662110175;35933.28133437317;35954.92135235947;36010.279794404225;36016.639833601854;3982;3985.561588225293;4002.4396584478195;4011.939820902689;4049.777262250575;26105.73894261071;26325;26630.20109396721;27545.724542754717;27912.441751165064;3178.8887388941507;3276.8197749123788;3279.311344617633;3414.6926776589976;3423.0928803866996;332109;336210;385162;389005;395444.02277988713;44613;44773.55226447759;44821.775891120335;44851.999999999345;45042;45873.54126458693;45919.540804592194;46001.76999114983;46099.53900460953;46126;13757.17456952586;13829.585112446643;13875.959303052312;13878.375473103735;13932.58202253934;12043.578474753342;12109.757804843854;12115.454804533818;12136.69658258533;12178.695532611755;12067.87932120685;12107.273563586214;12147.392630368446;12153;12264.44809983535;5817.214676018683;5853.853653658693;5888.735006924613;5894.705264736746;5928.140419639214;1155.2779512804498;1185.4250688416112;1185.6502331812137;1188.3167178872184;1235.499622652809;20053;26961.730382696314;27015.324616884736;27200;27255.86372068127;1187.5843454790754;1293.3727142335958;1306.8366454193226;1318.3276528970293;1350.9932450337685;13605.727885442433;13989.090709103875;14184.787228191586;14212.928935355463;14237.572872813833;399127;525719;536196;655890;656023;50435.24347134796;50664.49335506598;51179.488205118214;51180.48819511832;51229.48770512247;1449.1305216869794;1483.2361333913043;1489.5680252726588;1490.0835985868612;1495.9252037398087;20907;23246;24215.394615134763;26107;27716.722832771415;41083.383749244385;41372;41597.584024160584;41746.37380439235;41806.79096604497;415092.9245353754;494165;498525;499007;499936;25089.498210035334;25661.10186143476;25724.485510289316;29940.101796946128;30303.69696303097;38306.80846595749;38625.22749544937;39102.80448597853;40314.798426007685;41022.58977410188;27470;28222.435551288447;28477.288067798472;28580.285492862844;28936.55317234172;28025.439491209654;28359;29526.261843454085;29565.704342956295;30087.247818804706;29702;33855.492167617995;34266.14334641654;34351.82824085864;34352.969410917714;26296.474070518867;28134.2966425841;29476.55785163267;30137.69862301349;30190.69809301879;19884.60230795405;23312.53374932458;23704.933278002874;23745.64381534244;23993 18:43:04 INFO - PROCESS | 1646 | |2;jquery.html;423.2381712916753;437.3374337878115;440.66729619137556;449.3619060933474;450.48644545218446;20076.598468030632;21108.15567377305;22259.666105008397;22441.55116897665;22479.438014049658;22251.332460026224;22314.88842555789;22962.425939351524;23165.884170579167;23438;5406.783728650849;6693.799186024415;7006.913928341115;7266.636668166597;7268.56388616682;7017.6140312282805;7085.008098866163;7146.964265178667;7147.714091436336;7153.749618763344;5343.519083282504;6683.665816709169;7023.859522809566;7073.292670732912;7146.8213294667785;6027.487663548603;7027.613481258529;7032.683529241196;7045.859082818315;7087.220405755388;5874.559408044371;6585.6707164641575;6910.723571057151;6910.723571057151;6911.274316196777;23709.88145059281;24947.126850560326;26200.213993580222;26502.337441564116;26798.73201267982;2821.590869323957;3037.6202974628172;3155.495120780664;3172.524121381788;3179.2528755742383;16348.83651163485;16538.173091345507;16745.497635070966;17129.97220166794;17357.78495505315;2139.2726472999257;2147.108949785837;2200.4168895242774;2264.728232612081;2265.478939843829;164365;164457;171627.14186428988;172497.2750272494;176307;21457.892710536347;21828.45428864259;22006.779932200792;22049.889750551145;22210.778407187438;32406.675933240367;32734.017979460656;32823;32884.67115328816;33021;10336.586536538605;10715.83926241107;10768.515416806262;10802.729931751612;10817.78364432707;8083.434159608889;8949.149830766197;8963.193312601898;9136.908630913738;9155.862662060075;7760.573168475788;9682.56428460721;9691.370060946016;9864.950675246579;9888.604455821687;3510.157562185057;3994.320965435883;4072.735272207297;4101;4114.0948991221885;867.9652813887374;884.4869975414244;903.4624398482893;927.4435338796803;946.0728486083539;20920.16319347209;21355.893220534108;21362.359129226148;21389.14443422277;21442.571148576943;940.167951363035;942.5993952570267;963.1187463470845;969.8108868770663;981.3621146254991;10811.945940270249;10990.615328463466;10992.340459572451;11073.50169242386;11091.611793587186;769016.1549192219;772654;778545;781918;785785;153148;156200;158004;160255;175081;1308.613958882132;1323.206076354199;1380.323641415699;1389.201209304654;1389.6178550898383;16515;18226.270949161884;19084.90457547759;20154.093065812365;20396;63856;64661.67669161624;65518;65674.67162664156;66391;89872;92254.61618075869;92981;93846;94738;11477.770444591228;11614.187006909435;11621.883781162418;11666.824997624874;11941.582044628396;10628.681139565826;12596.811047834106;12851.357432128169;12927.15973461722;12972.091953563537;8418.915810841812;10373.429461379697;10448.32085914413;10815.405152716676;11155.103632382352;8952.28381729473;9669.70990870275;10371.429571373345;10560.366378017343;10593.947030264799;11346.829797553208;11430.82853757211;11464.4267786612;11558.595449159238;11658.94170529142;11535.769284614093;11696.941515292368;11698.76602467929;11851.348175850411;11893.167478276437;10808.783824323313;11071.889281107085;11257.04315133223;11333.546658133582;11350.772984540097 18:43:04 INFO - PROCESS | 1646 | |3;mootools.html;1634.4197809777531;1639.8442147995936;1667.7581750646157;1682.8233035531277;1694.9152542372879;9776.364536305153;10046.54790534426;11129.72175695607;11843.348615826126;11854.881451185496;10076;10374.221933354991;10508.527116279767;10516.789664206712;10523.84214236787;2414.263649586875;2442.4748679034005;2449.216250799736;2452.3010941881566;2458.1519375815315;2419.842710223839;2449.632555116729;2452.3256104571205;2466.938326541842;2471.1968610201666;2420.2376251480728;2434.770440927329;2442.2673198040607;2442.9617412599664;2444.4866578018637;2381.9285421437385;2390.9282721518384;2400.231925783741;2426.9393265168337;2437.1104546840274;2452.558539462896;2463.741307162758;2475.962860557093;2476.2818782553036;2481.1812102006293;11488.770224595544;11536.250143740714;11588.536458541648;11593.130515211393;11606.013488853458;5994.7901823436405;6060.96969515154;6069;6157.322694503601;6162.291336496315;3617.2042150726797;3685.907852303687;3734.906627334311;3774.8112594370173;3786.7917264550438;1299.9740005199938;1918.3189967561593;1938.9224431022742;1992.1832048859899;2042.3974927396453;15919.124448155348;16020.359185632677;16027.839721602752;16041.598960025976;16071.035737855884;3879.4374815652136;4718.197906355927;4882.292067650171;4954.826581069718;4965.776540055706;9880.50597470138;12216.205946613441;12224.877751222552;12232.021438284915;12268.570600028956;5944.524438044946;6015.30823955242;6025.578209525291;6036.8188954331445;6044.093385992049;5253.44838791929;5420.53925416344;5487.122060470386;5493.423190565013;5503;3762.1535154589997;3781.3760729479877;3966.333199423876;4062.3906414037538;4140.482439695038;916.463868636855;1068.5458680060942;1068.70610582089;1069.1340014588209;1085.913126949842;951.7097285328041;969.1616751510035;979.4417182206074;996.0935548650838;997.5684892179745;6238.719257633417;6275.403836635513;6690.498212634072;6875.3812156906115;6919.930800692029;974.9707508774748;977.8973207813219;1063.5320458998171;1079.5519859258477;1083.8103331916882;4162.854300099542;4168.312228482267;4232.217039847638;4329.783510824446;4345.109252603209;13302.733945321039;13867;13980.790288145687;13999.790003149961;14002.019858610003;11854.94072529649;14027.78958315626;14033.649158770908;14064.226467544384;14093.436262549589;9833.4099954003;10178.847317290247;10346.637867674595;10508.579656813794;10519.78960420772;6566.507511936719;6690.230623478167;6701.597904125768;6703.262641109376;6716.664166791738;11442.42787860587;12351.320677362832;12546;12591.433385497858;12622.431990560262;12715.237085774883;12847.614571562868;12897.677558061123;12928.935355323163;13139.737205256033;8451.323894088335;8648.394612377073;8691.174338437839;8702.434341767765;8736.08271131522;10242.846357304497;10344;10396.16830653561;10488.947555262175;10505.212109092;8926.330525210506;8991.640334386813;9058.818823623622;9076.228520575825;9170.403923744936;8716.91283087161;8751.781205469913;8759.255463285692;8798.560071996502;8970.058143895058;8012.679492820455;8083.151269116894;8177.34581233488;8184.713535026245;8185.631646575799;5919.171316015847;5928.525717942641;5943.4948029416255;6106.145139680537;6137.110119032626;6103.267607887082;6107.389261073857;6143.232095988002;6159.599626024294;6237 18:43:04 INFO - PROCESS | 1646 | |4;prototype.html;349.62066158218335;353.74530338156535;394.98488883569223;396.142351808335;400.19161294185744;4599.747013914233;4942.728149951751;5140.742962851861;5472.972635136824;5568.49883510484;4289.6997210195295;4652.441706995157;4695.929561056587;4722.527747225285;5452.40023597404;4076.7961601919933;4100.73991177015;4133.016152246923;4270.572942705736;4444.066745983348;3363.275746645213;3858.961410385902;4061.887818334464;4080.877758616389;4466.292976829224;4437.889052773674;4474.664400169984;4475.731456112628;4477.462704475468;4511.842085527007;3526.5239192708914;4345.263073756567;4431.933520997171;4458.777061146962;4459.710118842298;3544.85820567177;4070.3080476319096;4084.7125609658688;4109.609587089221;4147.4815648044;4131.793410329502;4752.334673145728;4791.053502096221;4893;5252.789888404459;4409.757463339515;4655.301704744282;5027.773750181214;5105.897882042375;5226.7909283628605;4541.500434952152;5249.795086063862;5723.198752174699;5726;5780.450857168562;4053.3717273822604;4080.5511393746665;4356.172327257811;4533.387992620987;4573.839915602971;4698.788554515021;5459.044667183227;6008.519318454513;6020.96989515054;6025.674351642631;4728.645351598575;4754.881127971759;4758.262469317261;4784.234522476387;4827;4121.773302468393;4312.7627980461375;4512.72923624577;4831.782569784368;4858.514148585114;4157.958420415757;4744.905101897943;4802.309263522737;5196.53231209193;5218.321618189687;5206.229881840192;5352.759125839347;5509.917351239735;5671.901396483245;5683.886322273532;1640.1307307127242;1641.0809946430038;1644.6464010237617;1652.8264532223943;1658.7677725118492;1000.7848312612823;1084.1110289562482;1118.9160812939062;1147.1167201254987;1198.4966314148078;4628.3288923105965;4891.486393928657;4974.9751251243515;5256.898429926362;5463.508284254436;4676.485586585472;5220.903610241816;5364.651297665718;5776.451237132465;5982.700864956735;4403.493598236244;4731.716097034189;4927.630427717935;5062.772584891583;5079.568236699922;2469.5678256305073;2840.0343883079854;2917;2951.1441681912434;3207.935841283208;5140.845774626767;5153.896922061538;5510.448955104457;5713.457221563945;6176.66028368435;5325.920111198335;5380.085385484477;5894.6757928314355;6143.32423433422;6242.09489624002;5513.69931453467;5980.910286345708;6013.879722405527;6093.512518998381;6198.72105755233;5019.745063734066;5399.811006614749;5680.6875621839545;6013.458788709037;6143.56995010345;5219.660722053054;5235.528802407802;5500.257465242301;5794.3626201117;5870.530357571299;5712.457316554921;6205;6207.099970504161;6207.224096987877;6277.121203031488;3884.6309600587897;4178.811953462162;4307.418498502662;4512.9774351128035;4675.143007019291;4887.755612219304;5088.338515992898;5444.401115877329;5866.826634673168;6018.668973206516;4873.805047798049;5042;5398.676079435247;5905.734241959208;5917.911231331447;4399.978000109979;5712.685802280748;5859.648421094748;5950.7917222896995;5974.2830860297045;5139.717315547681;5210.713410762292;5711.428857114422;5711.5145212655725;5717.285339332583;4841.661083724105;5408.971635636636;5526.668399896019;5545.61359660085;5850.94149058521;4840.34655321527;4892.319552476265;4905.386826646669;5278.683279003272;5613.961417137843 18:43:04 INFO - PROCESS | 1646 | |5;yui.html;738.5162718419438;788.8580055589991;807.3057170833079;866.3199388480042;875.7722992022073;14070.718585628281;15911.886167968232;17235.224414901328;17260.309587616495;17293.65412691747;15979.600509987227;16095;16118.355265789383;16166.75749863753;16181.35274589015;971.4851128901659;972.2125078686269;1013.9949300253526;1016.3038318751642;1027.2193133218732;1020.7397113735987;1029.0532709906904;1029.4441001858986;1029.67050543826;1030.6753372687617;1064.4943651765388;1065.398050101694;1067.5836423794726;1068.4978060311641;1069.7486090768664;1049.218332342406;1055.3615062886959;1063.3141623652778;1063.7074804428767;1065.110632621758;786.0999155966387;789.3921680306189;790.4071946040469;790.6560646118957;792.2157064506155;16412.09733464659;16929;17480.562985925328;17562.594992400576;17644.382446614305;12453.93773031129;14400.423983040771;14936.850631493655;15081;15330.15684137372;11949.402529873472;12119.272843629411;12238.143329966906;12249.816252756216;12524.311162886037;3834.6740527055235;3835.9424608630893;3862.7013164474206;3872.5159355080614;3932.3708206686792;115814;116466;119307;120659.99999999913;121468.17797733042;11315.20793544444;11387.943060284812;11652.53389864396;11879.881201187878;12270.938645306716;23968.64047039296;24374;24633.753662463147;24649.383765405648;24662.630060549105;7989.920100799034;8053.476524025919;8317.95841020791;8392.832143357098;8491.150884911583;4558.635309175324;4599.862004139881;4657.510961349078;4665.533446655375;4698.2247929092;3473.583170019614;3590.3537363274863;3616.023673608111;3723.422869455239;3864.4589757433982;710.4884483172108;711.4095300900344;716.2586722742042;734.9485536012535;736.9130532464571;652.6927311952226;664.6875968294894;675.9493038022168;682.4983637026781;686.4474098350776;10704.771615211948;11208.383538905438;11802.454664209698;11815.822762658567;11888.227265227733;593.2910172344008;618.6875627807929;623.2769986815273;624.0202881476039;744.9515781474187;4941.777620007037;5022.59819214462;5247.265382846328;5252.5535329497425;5261.815836445706;88268;97580.0241997571;98011.50994244983;100773;107621;61827.38172618216;80313.59843200746;82141.58929205436;85801;94705;69035;75999;78169.60915195501;83680.32639347178;84620.30759384779;11185.608503702331;11197.832032519356;11202.775944481227;11354.091672666002;11541.711457213301;40125.59874401218;49413.75293123511;49591;49969;50932.98134037373;63014;66235.00647490195;66382;67080;67355.65288694296;12742.872571274169;12924.159929604546;13188.604341869759;13223.471061157446;13276.137051091648;14914.627134321729;14950.729188018706;15191.696166076837;15338.818249813014;16000.519984400487;13682.31588420555;14379;14397.208153551655;14453.638659033608;14554.27228638585;12978.286194259406;12997.935010325265;13298.00264980111;13983.930080349533;14042.227677477837;13376.732465350833;13393.598192054254;13845.030847840555;13853.445862165401;13903.652408689864;9980.800383992424;10407.167426606005;10519.26365154432;10547.367157970546;10944.39805810688;9308.83639545057;11266.887331126583;11352.716182095513;11446.198766086627;11452.312861228353 18:43:04 INFO - PROCESS | 1646 | __end_tp_report 18:43:04 INFO - PROCESS | 1646 | __start_cc_report 18:43:04 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,399 18:43:04 INFO - PROCESS | 1646 | __end_cc_report 18:43:04 INFO - PROCESS | 1646 | __startTimestamp1495503784319__endTimestamp 18:43:04 INFO - PROCESS | 1646 | 18:43:04 INFO - PROCESS | 1646 | ------- Summary: start ------- 18:43:04 INFO - PROCESS | 1646 | Number of tests: 6 18:43:04 INFO - PROCESS | 1646 | 18:43:04 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:6128.89 Median:6334.78 stddev:731.32 (11.5%) stddev-sans-first:690.86 18:43:04 INFO - PROCESS | 1646 | Values: 2990.0 3203.5 3633.2 3656.9 3663.9 5920.5 6139.2 6180.8 6200.5 6221.6 5439.5 6183.6 6199.0 6211.6 6215.3 4625.0 5602.6 5705.3 5705.9 5792.8 5746.1 5774.0 5789.8 5804.8 5816.9 5402.5 5510.8 5538.9 5708.1 5772.5 5681.2 5706.5 5711.1 5760.5 5762.9 5607.0 5662.8 5675.1 5688.1 5752.5 4657.7 5043.8 5427.8 6104.2 6219.2 5625.7 6351.1 6540.5 6577.3 6600.3 6705.8 6747.9 6766.4 6767.9 6780.5 6149.7 6289.0 6299.9 6313.5 6314.2 6561.5 6794.6 6814.2 6831.6 6868.7 6564.6 6588.3 6591.5 6605.7 6612.4 6448.8 6654.7 6704.2 6711.3 6732.4 6440.1 6527.2 6571.9 6600.4 6616.4 6648.6 6657.8 6660.2 6672.3 6677.0 5155.3 5208.3 5281.0 5302.7 5344.2 4566.0 4725.7 4778.6 4790.3 4794.2 6208.4 6279.0 6296.6 6311.0 6324.1 6763.2 6763.5 6783.8 6793.8 6828.2 6148.9 6208.0 6316.9 6346.7 6348.7 4889.0 5890.0 5928.4 5965.0 5982.4 5587.8 5705.7 6891.3 6942.9 6954.7 6388.5 6751.9 6752.5 6756.5 6761.2 6776.1 6827.6 6828.5 6839.6 6923.7 6614.6 6628.9 6631.8 6639.1 6643.5 6572.0 6683.0 6707.7 6727.8 6729.9 6254.6 6284.2 6420.8 6438.2 6483.9 6517.9 6531.1 6545.9 6550.7 6554.1 6247.6 6274.8 6292.3 6390.3 6398.5 5787.0 6379.4 6431.1 6561.5 6564.1 6334.8 6383.6 6406.7 6420.5 6422.6 18:43:04 INFO - PROCESS | 1646 | 18:43:04 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:58753.90 Median:29545.98 stddev:127715.68 (432.3%) stddev-sans-first:127999.72 18:43:04 INFO - PROCESS | 1646 | Values: 755.0 826.9 851.2 871.2 988.6 38807.8 41131.0 41207.0 41364.6 41378.0 39807.4 40002.8 40050.6 40089.2 40123.6 6960.7 8436.5 11194.4 11578.4 11771.2 10370.8 11361.7 11482.0 11483.3 11498.0 10694.9 11527.0 11528.8 11536.4 11548.1 11435.0 11464.6 11465.4 11470.3 11484.8 9790.0 11269.3 11278.2 11305.8 11315.4 35677.9 35933.3 35954.9 36010.3 36016.6 3982.0 3985.6 4002.4 4011.9 4049.8 26105.7 26325.0 26630.2 27545.7 27912.4 3178.9 3276.8 3279.3 3414.7 3423.1 332109.0 336210.0 385162.0 389005.0 395444.0 44613.0 44773.6 44821.8 44852.0 45042.0 45873.5 45919.5 46001.8 46099.5 46126.0 13757.2 13829.6 13876.0 13878.4 13932.6 12043.6 12109.8 12115.5 12136.7 12178.7 12067.9 12107.3 12147.4 12153.0 12264.4 5817.2 5853.9 5888.7 5894.7 5928.1 1155.3 1185.4 1185.7 1188.3 1235.5 20053.0 26961.7 27015.3 27200.0 27255.9 1187.6 1293.4 1306.8 1318.3 1351.0 13605.7 13989.1 14184.8 14212.9 14237.6 399127.0 525719.0 536196.0 655890.0 656023.0 50435.2 50664.5 51179.5 51180.5 51229.5 1449.1 1483.2 1489.6 1490.1 1495.9 20907.0 23246.0 24215.4 26107.0 27716.7 41083.4 41372.0 41597.6 41746.4 41806.8 415092.9 494165.0 498525.0 499007.0 499936.0 25089.5 25661.1 25724.5 29940.1 30303.7 38306.8 38625.2 39102.8 40314.8 41022.6 27470.0 28222.4 28477.3 28580.3 28936.6 28025.4 28359.0 29526.3 29565.7 30087.2 29702.0 33855.5 34266.1 34351.8 34353.0 26296.5 28134.3 29476.6 30137.7 30190.7 19884.6 23312.5 23704.9 23745.6 23993.0 18:43:04 INFO - PROCESS | 1646 | 18:43:04 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:44812.58 Median:23301.94 stddev:130083.62 (558.3%) stddev-sans-first:130405.84 18:43:04 INFO - PROCESS | 1646 | Values: 423.2 437.3 440.7 449.4 450.5 20076.6 21108.2 22259.7 22441.6 22479.4 22251.3 22314.9 22962.4 23165.9 23438.0 5406.8 6693.8 7006.9 7266.6 7268.6 7017.6 7085.0 7147.0 7147.7 7153.7 5343.5 6683.7 7023.9 7073.3 7146.8 6027.5 7027.6 7032.7 7045.9 7087.2 5874.6 6585.7 6910.7 6910.7 6911.3 23709.9 24947.1 26200.2 26502.3 26798.7 2821.6 3037.6 3155.5 3172.5 3179.3 16348.8 16538.2 16745.5 17130.0 17357.8 2139.3 2147.1 2200.4 2264.7 2265.5 164365.0 164457.0 171627.1 172497.3 176307.0 21457.9 21828.5 22006.8 22049.9 22210.8 32406.7 32734.0 32823.0 32884.7 33021.0 10336.6 10715.8 10768.5 10802.7 10817.8 8083.4 8949.1 8963.2 9136.9 9155.9 7760.6 9682.6 9691.4 9865.0 9888.6 3510.2 3994.3 4072.7 4101.0 4114.1 868.0 884.5 903.5 927.4 946.1 20920.2 21355.9 21362.4 21389.1 21442.6 940.2 942.6 963.1 969.8 981.4 10811.9 10990.6 10992.3 11073.5 11091.6 769016.2 772654.0 778545.0 781918.0 785785.0 153148.0 156200.0 158004.0 160255.0 175081.0 1308.6 1323.2 1380.3 1389.2 1389.6 16515.0 18226.3 19084.9 20154.1 20396.0 63856.0 64661.7 65518.0 65674.7 66391.0 89872.0 92254.6 92981.0 93846.0 94738.0 11477.8 11614.2 11621.9 11666.8 11941.6 10628.7 12596.8 12851.4 12927.2 12972.1 8418.9 10373.4 10448.3 10815.4 11155.1 8952.3 9669.7 10371.4 10560.4 10593.9 11346.8 11430.8 11464.4 11558.6 11658.9 11535.8 11696.9 11698.8 11851.3 11893.2 10808.8 11071.9 11257.0 11333.5 11350.8 18:43:04 INFO - PROCESS | 1646 | 18:43:04 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:6859.76 Median:2473.58 stddev:4251.67 (171.9%) stddev-sans-first:4245.47 18:43:04 INFO - PROCESS | 1646 | Values: 1634.4 1639.8 1667.8 1682.8 1694.9 9776.4 10046.5 11129.7 11843.3 11854.9 10076.0 10374.2 10508.5 10516.8 10523.8 2414.3 2442.5 2449.2 2452.3 2458.2 2419.8 2449.6 2452.3 2466.9 2471.2 2420.2 2434.8 2442.3 2443.0 2444.5 2381.9 2390.9 2400.2 2426.9 2437.1 2452.6 2463.7 2476.0 2476.3 2481.2 11488.8 11536.3 11588.5 11593.1 11606.0 5994.8 6061.0 6069.0 6157.3 6162.3 3617.2 3685.9 3734.9 3774.8 3786.8 1300.0 1918.3 1938.9 1992.2 2042.4 15919.1 16020.4 16027.8 16041.6 16071.0 3879.4 4718.2 4882.3 4954.8 4965.8 9880.5 12216.2 12224.9 12232.0 12268.6 5944.5 6015.3 6025.6 6036.8 6044.1 5253.4 5420.5 5487.1 5493.4 5503.0 3762.2 3781.4 3966.3 4062.4 4140.5 916.5 1068.5 1068.7 1069.1 1085.9 951.7 969.2 979.4 996.1 997.6 6238.7 6275.4 6690.5 6875.4 6919.9 975.0 977.9 1063.5 1079.6 1083.8 4162.9 4168.3 4232.2 4329.8 4345.1 13302.7 13867.0 13980.8 13999.8 14002.0 11854.9 14027.8 14033.6 14064.2 14093.4 9833.4 10178.8 10346.6 10508.6 10519.8 6566.5 6690.2 6701.6 6703.3 6716.7 11442.4 12351.3 12546.0 12591.4 12622.4 12715.2 12847.6 12897.7 12928.9 13139.7 8451.3 8648.4 8691.2 8702.4 8736.1 10242.8 10344.0 10396.2 10488.9 10505.2 8926.3 8991.6 9058.8 9076.2 9170.4 8716.9 8751.8 8759.3 8798.6 8970.1 8012.7 8083.2 8177.3 8184.7 8185.6 5919.2 5928.5 5943.5 6106.1 6137.1 6103.3 6107.4 6143.2 6159.6 6237.0 18:43:04 INFO - PROCESS | 1646 | 18:43:04 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4670.21 Median:4892.66 stddev:1326.68 (27.1%) stddev-sans-first:1290.16 18:43:04 INFO - PROCESS | 1646 | Values: 349.6 353.7 395.0 396.1 400.2 4599.7 4942.7 5140.7 5473.0 5568.5 4289.7 4652.4 4695.9 4722.5 5452.4 4076.8 4100.7 4133.0 4270.6 4444.1 3363.3 3859.0 4061.9 4080.9 4466.3 4437.9 4474.7 4475.7 4477.5 4511.8 3526.5 4345.3 4431.9 4458.8 4459.7 3544.9 4070.3 4084.7 4109.6 4147.5 4131.8 4752.3 4791.1 4893.0 5252.8 4409.8 4655.3 5027.8 5105.9 5226.8 4541.5 5249.8 5723.2 5726.0 5780.5 4053.4 4080.6 4356.2 4533.4 4573.8 4698.8 5459.0 6008.5 6021.0 6025.7 4728.6 4754.9 4758.3 4784.2 4827.0 4121.8 4312.8 4512.7 4831.8 4858.5 4158.0 4744.9 4802.3 5196.5 5218.3 5206.2 5352.8 5509.9 5671.9 5683.9 1640.1 1641.1 1644.6 1652.8 1658.8 1000.8 1084.1 1118.9 1147.1 1198.5 4628.3 4891.5 4975.0 5256.9 5463.5 4676.5 5220.9 5364.7 5776.5 5982.7 4403.5 4731.7 4927.6 5062.8 5079.6 2469.6 2840.0 2917.0 2951.1 3207.9 5140.8 5153.9 5510.4 5713.5 6176.7 5325.9 5380.1 5894.7 6143.3 6242.1 5513.7 5980.9 6013.9 6093.5 6198.7 5019.7 5399.8 5680.7 6013.5 6143.6 5219.7 5235.5 5500.3 5794.4 5870.5 5712.5 6205.0 6207.1 6207.2 6277.1 3884.6 4178.8 4307.4 4513.0 4675.1 4887.8 5088.3 5444.4 5866.8 6018.7 4873.8 5042.0 5398.7 5905.7 5917.9 4400.0 5712.7 5859.6 5950.8 5974.3 5139.7 5210.7 5711.4 5711.5 5717.3 4841.7 5409.0 5526.7 5545.6 5850.9 4840.3 4892.3 4905.4 5278.7 5614.0 18:43:04 INFO - PROCESS | 1646 | 18:43:04 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:20841.84 Median:16670.55 stddev:29545.27 (177.2%) stddev-sans-first:29589.59 18:43:04 INFO - PROCESS | 1646 | Values: 738.5 788.9 807.3 866.3 875.8 14070.7 15911.9 17235.2 17260.3 17293.7 15979.6 16095.0 16118.4 16166.8 16181.4 971.5 972.2 1014.0 1016.3 1027.2 1020.7 1029.1 1029.4 1029.7 1030.7 1064.5 1065.4 1067.6 1068.5 1069.7 1049.2 1055.4 1063.3 1063.7 1065.1 786.1 789.4 790.4 790.7 792.2 16412.1 16929.0 17480.6 17562.6 17644.4 12453.9 14400.4 14936.9 15081.0 15330.2 11949.4 12119.3 12238.1 12249.8 12524.3 3834.7 3835.9 3862.7 3872.5 3932.4 115814.0 116466.0 119307.0 120660.0 121468.2 11315.2 11387.9 11652.5 11879.9 12270.9 23968.6 24374.0 24633.8 24649.4 24662.6 7989.9 8053.5 8318.0 8392.8 8491.2 4558.6 4599.9 4657.5 4665.5 4698.2 3473.6 3590.4 3616.0 3723.4 3864.5 710.5 711.4 716.3 734.9 736.9 652.7 664.7 675.9 682.5 686.4 10704.8 11208.4 11802.5 11815.8 11888.2 593.3 618.7 623.3 624.0 745.0 4941.8 5022.6 5247.3 5252.6 5261.8 88268.0 97580.0 98011.5 100773.0 107621.0 61827.4 80313.6 82141.6 85801.0 94705.0 69035.0 75999.0 78169.6 83680.3 84620.3 11185.6 11197.8 11202.8 11354.1 11541.7 40125.6 49413.8 49591.0 49969.0 50933.0 63014.0 66235.0 66382.0 67080.0 67355.7 12742.9 12924.2 13188.6 13223.5 13276.1 14914.6 14950.7 15191.7 15338.8 16000.5 13682.3 14379.0 14397.2 14453.6 14554.3 12978.3 12997.9 13298.0 13983.9 14042.2 13376.7 13393.6 13845.0 13853.4 13903.7 9980.8 10407.2 10519.3 10547.4 10944.4 9308.8 11266.9 11352.7 11446.2 11452.3 18:43:04 INFO - PROCESS | 1646 | -------- Summary: end -------- 18:43:04 INFO - PROCESS | 1646 | 18:43:04 INFO - TEST-INFO | 1646: exit 0 18:43:04 INFO - TEST-OK | dromaeo_css | took 1115352ms 18:43:04 INFO - TEST-START | kraken 18:43:04 INFO - operating with platform_type : mac_ 18:43:04 INFO - Initialising browser for kraken test... 18:43:04 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmp9LirZ0/profile http://localhost:49224/getInfo.html) 18:43:07 INFO - PROCESS | 1658 | 2017-05-22 18:43:07.596 plugin-container[1660:10284] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8543, name = 'com.apple.tsm.portname' 18:43:07 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:43:07 INFO - PROCESS | 1658 | 2017-05-22 18:43:07.597 plugin-container[1660:10284] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 18:43:07 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:43:07 INFO - PROCESS | 1658 | 2017-05-22 18:43:07.597 plugin-container[1660:10284] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:43:07 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 18:43:07 INFO - PROCESS | 1658 | colorDepth:24 18:43:07 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 18:43:07 INFO - PROCESS | 1658 | __metrics 18:43:07 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 18:43:08 INFO - TEST-INFO | 1658: exit 0 18:43:08 INFO - Browser initialized. 18:43:08 INFO - Running cycle 1/1 for kraken test... 18:43:08 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmp9LirZ0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:43:10 INFO - PROCESS | 1661 | 2017-05-22 18:43:10.443 plugin-container[1662:10413] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 18:43:10 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:43:10 INFO - PROCESS | 1661 | 2017-05-22 18:43:10.444 plugin-container[1662:10413] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 18:43:10 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:43:10 INFO - PROCESS | 1661 | 2017-05-22 18:43:10.444 plugin-container[1662:10413] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:43:20 INFO - PROCESS | 1661 | RSS: Main: 174362624 18:43:20 INFO - PROCESS | 1661 | 18:43:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 18:43:24 INFO - PROCESS | 1661 | RSS: Main: 188137472 18:43:24 INFO - PROCESS | 1661 | 18:43:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 18:43:27 INFO - PROCESS | 1661 | RSS: Main: 196034560 18:43:27 INFO - PROCESS | 1661 | 18:43:30 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 18:43:31 INFO - PROCESS | 1661 | RSS: Main: 199884800 18:43:31 INFO - PROCESS | 1661 | 18:43:33 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 18:43:33 INFO - PROCESS | 1661 | RSS: Main: 201142272 18:43:33 INFO - PROCESS | 1661 | 18:43:36 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:43:36 INFO - PROCESS | 1661 | RSS: Main: 201494528 18:43:36 INFO - PROCESS | 1661 | 18:43:39 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 18:43:40 INFO - PROCESS | 1661 | RSS: Main: 205983744 18:43:40 INFO - PROCESS | 1661 | 18:43:43 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 18:43:43 INFO - PROCESS | 1661 | RSS: Main: 204865536 18:43:43 INFO - PROCESS | 1661 | 18:43:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 18:43:47 INFO - PROCESS | 1661 | RSS: Main: 204824576 18:43:47 INFO - PROCESS | 1661 | 18:43:49 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:43:49 INFO - PROCESS | 1661 | RSS: Main: 204947456 18:43:49 INFO - PROCESS | 1661 | 18:43:51 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:43:52 INFO - PROCESS | 1661 | RSS: Main: 204390400 18:43:52 INFO - PROCESS | 1661 | 18:43:54 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:43:55 INFO - PROCESS | 1661 | RSS: Main: 204406784 18:43:55 INFO - PROCESS | 1661 | 18:43:57 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:43:58 INFO - PROCESS | 1661 | RSS: Main: 204771328 18:43:58 INFO - PROCESS | 1661 | 18:44:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:44:02 INFO - PROCESS | 1661 | RSS: Main: 204845056 18:44:02 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 18:44:04 INFO - PROCESS | 1661 | RSS: Main: 206872576 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | __start_tp_report 18:44:04 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 18:44:04 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 18:44:04 INFO - PROCESS | 1661 | |i|pagename|runs| 18:44:04 INFO - PROCESS | 1661 | |0;ai-astar;109;119;129;174;132;96;159;114;168;179 18:44:04 INFO - PROCESS | 1661 | |1;audio-beat-detection;136;112;109;140;110;116;142;140;129;111 18:44:04 INFO - PROCESS | 1661 | |2;audio-dft;261;192;197;209;196;199;196;192;195;195 18:44:04 INFO - PROCESS | 1661 | |3;audio-fft;81;59;61;84;61;60;60;63;84;86 18:44:04 INFO - PROCESS | 1661 | |4;audio-oscillator;84;73;106;102;104;101;116;69;69;81 18:44:04 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;136;89;89;88;95;131;88;90;90;86 18:44:04 INFO - PROCESS | 1661 | |6;imaging-darkroom;120;96;96;96;96;118;96;96;96;95 18:44:04 INFO - PROCESS | 1661 | |7;imaging-desaturate;89;100;92;91;92;92;81;143;142;92 18:44:04 INFO - PROCESS | 1661 | |8;json-parse-financial;66;67;67;79;70;69;67;68;68;79 18:44:04 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;59;65;63;67;62;57;57;75;54;64 18:44:04 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;83;81;82;82;83;80;86;84;83;90 18:44:04 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;134;138;137;134;135;163;155;156;206;183 18:44:04 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;168;160;224;231;202;165;167;161;164;167 18:44:04 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;64;65;65;64;65;69;76;80;85;83 18:44:04 INFO - PROCESS | 1661 | __end_tp_report 18:44:04 INFO - PROCESS | 1661 | __start_cc_report 18:44:04 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,991 18:44:04 INFO - PROCESS | 1661 | __end_cc_report 18:44:04 INFO - PROCESS | 1661 | __startTimestamp1495503844372__endTimestamp 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | ------- Summary: start ------- 18:44:04 INFO - PROCESS | 1661 | Number of tests: 14 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:137.90 Median:163.50 stddev:29.77 (18.2%) stddev-sans-first:29.68 18:44:04 INFO - PROCESS | 1661 | Values: 109.0 119.0 129.0 174.0 132.0 96.0 159.0 114.0 168.0 179.0 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:124.50 Median:132.50 stddev:14.14 (10.7%) stddev-sans-first:14.38 18:44:04 INFO - PROCESS | 1661 | Values: 136.0 112.0 109.0 140.0 110.0 116.0 142.0 140.0 129.0 111.0 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:203.20 Median:196.50 stddev:20.87 (10.6%) stddev-sans-first:5.09 18:44:04 INFO - PROCESS | 1661 | Values: 261.0 192.0 197.0 209.0 196.0 199.0 196.0 192.0 195.0 195.0 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:69.90 Median:72.00 stddev:12.02 (16.7%) stddev-sans-first:12.06 18:44:04 INFO - PROCESS | 1661 | Values: 81.0 59.0 61.0 84.0 61.0 60.0 60.0 63.0 84.0 86.0 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:69.00 stddev:17.25 (25.0%) stddev-sans-first:18.14 18:44:04 INFO - PROCESS | 1661 | Values: 84.0 73.0 106.0 102.0 104.0 101.0 116.0 69.0 69.0 81.0 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:98.20 Median:89.00 stddev:18.78 (21.1%) stddev-sans-first:14.09 18:44:04 INFO - PROCESS | 1661 | Values: 136.0 89.0 89.0 88.0 95.0 131.0 88.0 90.0 90.0 86.0 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:100.50 Median:96.00 stddev:9.77 (10.2%) stddev-sans-first:7.38 18:44:04 INFO - PROCESS | 1661 | Values: 120.0 96.0 96.0 96.0 96.0 118.0 96.0 96.0 96.0 95.0 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:101.40 Median:91.50 stddev:22.14 (24.2%) stddev-sans-first:23.03 18:44:04 INFO - PROCESS | 1661 | Values: 89.0 100.0 92.0 91.0 92.0 92.0 81.0 143.0 142.0 92.0 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:70.00 Median:68.50 stddev:4.88 (7.1%) stddev-sans-first:4.95 18:44:04 INFO - PROCESS | 1661 | Values: 66.0 67.0 67.0 79.0 70.0 69.0 67.0 68.0 68.0 79.0 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:62.30 Median:63.50 stddev:6.06 (9.5%) stddev-sans-first:6.30 18:44:04 INFO - PROCESS | 1661 | Values: 59.0 65.0 63.0 67.0 62.0 57.0 57.0 75.0 54.0 64.0 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:83.00 stddev:2.84 (3.4%) stddev-sans-first:3.00 18:44:04 INFO - PROCESS | 1661 | Values: 83.0 81.0 82.0 82.0 83.0 80.0 86.0 84.0 83.0 90.0 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:154.10 Median:155.50 stddev:24.35 (15.7%) stddev-sans-first:24.72 18:44:04 INFO - PROCESS | 1661 | Values: 134.0 138.0 137.0 134.0 135.0 163.0 155.0 156.0 206.0 183.0 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.90 Median:167.50 stddev:27.36 (16.3%) stddev-sans-first:28.62 18:44:04 INFO - PROCESS | 1661 | Values: 168.0 160.0 224.0 231.0 202.0 165.0 167.0 161.0 164.0 167.0 18:44:04 INFO - PROCESS | 1661 | 18:44:04 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:72.50 stddev:8.51 (11.7%) stddev-sans-first:8.57 18:44:04 INFO - PROCESS | 1661 | Values: 64.0 65.0 65.0 64.0 65.0 69.0 76.0 80.0 85.0 83.0 18:44:04 INFO - PROCESS | 1661 | -------- Summary: end -------- 18:44:04 INFO - PROCESS | 1661 | 18:44:05 INFO - TEST-INFO | 1661: exit 0 18:44:05 INFO - TEST-OK | kraken | took 60271ms 18:44:05 INFO - SUITE-END | took 1175s 18:44:05 INFO - Completed test suite (00:19:35) 18:44:05 INFO - javascript benchmark 18:44:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7892.660219917395, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2989.95515067274, 3203.519472079188, 3633.182533929866, 3656.8720094796686, 3663.8534458621657, 5920.526357891369, 6139.2019037525115, 6180.783672571461, 6200.534959878005, 6221.595596286238, 5439.456054394558, 6183.598066125699, 6199.0, 6211.56519043666, 6215.316315205324, 4624.97687511562, 5602.60781745278, 5705.25831641886, 5705.885882282352, 5792.826215213529, 5746.109353050263, 5774.0, 5789.8263052108505, 5804.82585522435, 5816.883662326751, 5402.486763757456, 5510.779568817243, 5538.916916246239, 5708.1437784332475, 5772.538196944234, 5681.176229446728, 5706.457886500776, 5711.143328500717, 5760.481556659921, 5762.88474230513, 5606.971965140148, 5662.8301150965535, 5675.120356344773, 5688.11834165705, 5752.539796816286, 4657.650676199265, 5043.84868453947, 5427.8330159016, 6104.236970378703, 6219.1915051043525, 5625.6905870177125, 6351.142595749561, 6540.509461790385, 6577.309382514815, 6600.273969863311, 6705.798826035227, 6747.865042699168, 6766.357196066366, 6767.932320676829, 6780.525363224576, 6149.723262453111, 6288.968555157195, 6299.905501417483, 6313.494920406357, 6314.242290925118, 6561.507886908501, 6794.626295553693, 6814.182298124158, 6831.555948863308, 6868.656567171622, 6564.638944857983, 6588.275289718127, 6591.472682185414, 6605.669716514155, 6612.404883560503, 6448.838779030561, 6654.6672666366485, 6704.229013663394, 6711.295313992058, 6732.360425759545, 6440.098386225932, 6527.15147030883, 6571.901421478682, 6600.438962688226, 6616.437602803816, 6648.634325112069, 6657.800265992028, 6660.167479065117, 6672.299408562031, 6676.96661516699, 5155.304033955443, 5208.322918020709, 5281.049411106038, 5302.68183908959, 5344.198370244494, 4565.977170114128, 4725.740084295397, 4778.617710583145, 4790.329353890458, 4794.184988551885, 6208.441240288395, 6279.0, 6296.622202667763, 6310.990241561328, 6324.114623952651, 6763.188417389944, 6763.458923286125, 6783.830404239835, 6793.762218322335, 6828.2488926218075, 6148.907766383508, 6208.0, 6316.905246421307, 6346.714397852108, 6348.682565871688, 4888.95111048885, 5890.028145355968, 5928.407159284037, 5964.956132676851, 5982.371850955674, 5587.804426845041, 5705.71471426427, 6891.310868913269, 6942.895856562257, 6954.687039083355, 6388.520860935356, 6751.864962700817, 6752.527323087336, 6756.45948324123, 6761.222459417033, 6776.051352810512, 6827.590344579143, 6828.453723702191, 6839.589624622538, 6923.688434020381, 6614.6361950093205, 6628.933710662832, 6631.767888123893, 6639.136912201383, 6643.534952553275, 6572.0, 6683.030960510796, 6707.66461676924, 6727.831804204934, 6729.899051514133, 6254.6247225166635, 6284.214473190851, 6420.775272865428, 6438.227412710505, 6483.935160648333, 6517.902231466626, 6531.085648009377, 6545.901811472927, 6550.672466376757, 6554.115194448879, 6247.625142491465, 6274.780382686584, 6292.3078461368295, 6390.329015453496, 6398.488120950406, 5786.971065144648, 6379.425851673372, 6431.099646049463, 6561.507886908596, 6564.113844631104, 6334.778282760266, 6383.616982981036, 6406.679666016774, 6420.454261387835, 6422.614643121427], "value": 6080.966023466727, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [754.9581577423158, 826.9462484938479, 851.1573542193227, 871.2115535440429, 988.5946761827647, 38807.80596097019, 41130.97172570687, 41207.0, 41364.58635413645, 41377.99999999996, 39807.40288895669, 40002.799916002565, 40050.59949400505, 40089.198216035664, 40123.59876401242, 6960.721571137161, 8436.45163064399, 11194.440277986108, 11578.421078946061, 11771.234869733482, 10370.75550933889, 11361.659150225465, 11482.0, 11483.253588516762, 11498.0, 10694.946525267364, 11527.020203282731, 11528.769424611502, 11536.365499897502, 11548.076153907667, 11435.028022618088, 11464.598739044135, 11465.369404682739, 11470.254433461798, 11484.770304593903, 9790.0, 11269.323840569592, 11278.210525263235, 11305.773884522347, 11315.434228288635, 35677.929662110175, 35933.28133437317, 35954.92135235947, 36010.279794404225, 36016.639833601854, 3982.0, 3985.561588225293, 4002.4396584478195, 4011.939820902689, 4049.777262250575, 26105.73894261071, 26325.0, 26630.20109396721, 27545.724542754717, 27912.441751165064, 3178.8887388941507, 3276.8197749123788, 3279.311344617633, 3414.6926776589976, 3423.0928803866996, 332109.0, 336210.0, 385162.0, 389005.0, 395444.02277988713, 44613.0, 44773.55226447759, 44821.775891120335, 44851.999999999345, 45042.0, 45873.54126458693, 45919.540804592194, 46001.76999114983, 46099.53900460953, 46126.0, 13757.17456952586, 13829.585112446643, 13875.959303052312, 13878.375473103735, 13932.58202253934, 12043.578474753342, 12109.757804843854, 12115.454804533818, 12136.69658258533, 12178.695532611755, 12067.87932120685, 12107.273563586214, 12147.392630368446, 12153.0, 12264.44809983535, 5817.214676018683, 5853.853653658693, 5888.735006924613, 5894.705264736746, 5928.140419639214, 1155.2779512804498, 1185.4250688416112, 1185.6502331812137, 1188.3167178872184, 1235.499622652809, 20053.0, 26961.730382696314, 27015.324616884736, 27200.0, 27255.86372068127, 1187.5843454790754, 1293.3727142335958, 1306.8366454193226, 1318.3276528970293, 1350.9932450337685, 13605.727885442433, 13989.090709103875, 14184.787228191586, 14212.928935355463, 14237.572872813833, 399127.0, 525719.0, 536196.0, 655890.0, 656023.0, 50435.24347134796, 50664.49335506598, 51179.488205118214, 51180.48819511832, 51229.48770512247, 1449.1305216869794, 1483.2361333913043, 1489.5680252726588, 1490.0835985868612, 1495.9252037398087, 20907.0, 23246.0, 24215.394615134763, 26107.0, 27716.722832771415, 41083.383749244385, 41372.0, 41597.584024160584, 41746.37380439235, 41806.79096604497, 415092.9245353754, 494165.0, 498525.0, 499007.0, 499936.0, 25089.498210035334, 25661.10186143476, 25724.485510289316, 29940.101796946128, 30303.69696303097, 38306.80846595749, 38625.22749544937, 39102.80448597853, 40314.798426007685, 41022.58977410188, 27470.0, 28222.435551288447, 28477.288067798472, 28580.285492862844, 28936.55317234172, 28025.439491209654, 28359.0, 29526.261843454085, 29565.704342956295, 30087.247818804706, 29702.0, 33855.492167617995, 34266.14334641654, 34351.82824085864, 34352.969410917714, 26296.474070518867, 28134.2966425841, 29476.55785163267, 30137.69862301349, 30190.69809301879, 19884.60230795405, 23312.53374932458, 23704.933278002874, 23745.64381534244, 23993.0], "value": 18678.122470335005, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.2381712916753, 437.3374337878115, 440.66729619137556, 449.3619060933474, 450.48644545218446, 20076.598468030632, 21108.15567377305, 22259.666105008397, 22441.55116897665, 22479.438014049658, 22251.332460026224, 22314.88842555789, 22962.425939351524, 23165.884170579167, 23438.0, 5406.783728650849, 6693.799186024415, 7006.913928341115, 7266.636668166597, 7268.56388616682, 7017.6140312282805, 7085.008098866163, 7146.964265178667, 7147.714091436336, 7153.749618763344, 5343.519083282504, 6683.665816709169, 7023.859522809566, 7073.292670732912, 7146.8213294667785, 6027.487663548603, 7027.613481258529, 7032.683529241196, 7045.859082818315, 7087.220405755388, 5874.559408044371, 6585.6707164641575, 6910.723571057151, 6910.723571057151, 6911.274316196777, 23709.88145059281, 24947.126850560326, 26200.213993580222, 26502.337441564116, 26798.73201267982, 2821.590869323957, 3037.6202974628172, 3155.495120780664, 3172.524121381788, 3179.2528755742383, 16348.83651163485, 16538.173091345507, 16745.497635070966, 17129.97220166794, 17357.78495505315, 2139.2726472999257, 2147.108949785837, 2200.4168895242774, 2264.728232612081, 2265.478939843829, 164365.0, 164457.0, 171627.14186428988, 172497.2750272494, 176307.0, 21457.892710536347, 21828.45428864259, 22006.779932200792, 22049.889750551145, 22210.778407187438, 32406.675933240367, 32734.017979460656, 32823.0, 32884.67115328816, 33021.0, 10336.586536538605, 10715.83926241107, 10768.515416806262, 10802.729931751612, 10817.78364432707, 8083.434159608889, 8949.149830766197, 8963.193312601898, 9136.908630913738, 9155.862662060075, 7760.573168475788, 9682.56428460721, 9691.370060946016, 9864.950675246579, 9888.604455821687, 3510.157562185057, 3994.320965435883, 4072.735272207297, 4101.0, 4114.0948991221885, 867.9652813887374, 884.4869975414244, 903.4624398482893, 927.4435338796803, 946.0728486083539, 20920.16319347209, 21355.893220534108, 21362.359129226148, 21389.14443422277, 21442.571148576943, 940.167951363035, 942.5993952570267, 963.1187463470845, 969.8108868770663, 981.3621146254991, 10811.945940270249, 10990.615328463466, 10992.340459572451, 11073.50169242386, 11091.611793587186, 769016.1549192219, 772654.0, 778545.0, 781918.0, 785785.0, 153148.0, 156200.0, 158004.0, 160255.0, 175081.0, 1308.613958882132, 1323.206076354199, 1380.323641415699, 1389.201209304654, 1389.6178550898383, 16515.0, 18226.270949161884, 19084.90457547759, 20154.093065812365, 20396.0, 63856.0, 64661.67669161624, 65518.0, 65674.67162664156, 66391.0, 89872.0, 92254.61618075869, 92981.0, 93846.0, 94738.0, 11477.770444591228, 11614.187006909435, 11621.883781162418, 11666.824997624874, 11941.582044628396, 10628.681139565826, 12596.811047834106, 12851.357432128169, 12927.15973461722, 12972.091953563537, 8418.915810841812, 10373.429461379697, 10448.32085914413, 10815.405152716676, 11155.103632382352, 8952.28381729473, 9669.70990870275, 10371.429571373345, 10560.366378017343, 10593.947030264799, 11346.829797553208, 11430.82853757211, 11464.4267786612, 11558.595449159238, 11658.94170529142, 11535.769284614093, 11696.941515292368, 11698.76602467929, 11851.348175850411, 11893.167478276437, 10808.783824323313, 11071.889281107085, 11257.04315133223, 11333.546658133582, 11350.772984540097], "value": 11766.179875900372, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1634.4197809777531, 1639.8442147995936, 1667.7581750646157, 1682.8233035531277, 1694.9152542372879, 9776.364536305153, 10046.54790534426, 11129.72175695607, 11843.348615826126, 11854.881451185496, 10076.0, 10374.221933354991, 10508.527116279767, 10516.789664206712, 10523.84214236787, 2414.263649586875, 2442.4748679034005, 2449.216250799736, 2452.3010941881566, 2458.1519375815315, 2419.842710223839, 2449.632555116729, 2452.3256104571205, 2466.938326541842, 2471.1968610201666, 2420.2376251480728, 2434.770440927329, 2442.2673198040607, 2442.9617412599664, 2444.4866578018637, 2381.9285421437385, 2390.9282721518384, 2400.231925783741, 2426.9393265168337, 2437.1104546840274, 2452.558539462896, 2463.741307162758, 2475.962860557093, 2476.2818782553036, 2481.1812102006293, 11488.770224595544, 11536.250143740714, 11588.536458541648, 11593.130515211393, 11606.013488853458, 5994.7901823436405, 6060.96969515154, 6069.0, 6157.322694503601, 6162.291336496315, 3617.2042150726797, 3685.907852303687, 3734.906627334311, 3774.8112594370173, 3786.7917264550438, 1299.9740005199938, 1918.3189967561593, 1938.9224431022742, 1992.1832048859899, 2042.3974927396453, 15919.124448155348, 16020.359185632677, 16027.839721602752, 16041.598960025976, 16071.035737855884, 3879.4374815652136, 4718.197906355927, 4882.292067650171, 4954.826581069718, 4965.776540055706, 9880.50597470138, 12216.205946613441, 12224.877751222552, 12232.021438284915, 12268.570600028956, 5944.524438044946, 6015.30823955242, 6025.578209525291, 6036.8188954331445, 6044.093385992049, 5253.44838791929, 5420.53925416344, 5487.122060470386, 5493.423190565013, 5503.0, 3762.1535154589997, 3781.3760729479877, 3966.333199423876, 4062.3906414037538, 4140.482439695038, 916.463868636855, 1068.5458680060942, 1068.70610582089, 1069.1340014588209, 1085.913126949842, 951.7097285328041, 969.1616751510035, 979.4417182206074, 996.0935548650838, 997.5684892179745, 6238.719257633417, 6275.403836635513, 6690.498212634072, 6875.3812156906115, 6919.930800692029, 974.9707508774748, 977.8973207813219, 1063.5320458998171, 1079.5519859258477, 1083.8103331916882, 4162.854300099542, 4168.312228482267, 4232.217039847638, 4329.783510824446, 4345.109252603209, 13302.733945321039, 13867.0, 13980.790288145687, 13999.790003149961, 14002.019858610003, 11854.94072529649, 14027.78958315626, 14033.649158770908, 14064.226467544384, 14093.436262549589, 9833.4099954003, 10178.847317290247, 10346.637867674595, 10508.579656813794, 10519.78960420772, 6566.507511936719, 6690.230623478167, 6701.597904125768, 6703.262641109376, 6716.664166791738, 11442.42787860587, 12351.320677362832, 12546.0, 12591.433385497858, 12622.431990560262, 12715.237085774883, 12847.614571562868, 12897.677558061123, 12928.935355323163, 13139.737205256033, 8451.323894088335, 8648.394612377073, 8691.174338437839, 8702.434341767765, 8736.08271131522, 10242.846357304497, 10344.0, 10396.16830653561, 10488.947555262175, 10505.212109092, 8926.330525210506, 8991.640334386813, 9058.818823623622, 9076.228520575825, 9170.403923744936, 8716.91283087161, 8751.781205469913, 8759.255463285692, 8798.560071996502, 8970.058143895058, 8012.679492820455, 8083.151269116894, 8177.34581233488, 8184.713535026245, 8185.631646575799, 5919.171316015847, 5928.525717942641, 5943.4948029416255, 6106.145139680537, 6137.110119032626, 6103.267607887082, 6107.389261073857, 6143.232095988002, 6159.599626024294, 6237.0], "value": 5298.730052052498, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.62066158218335, 353.74530338156535, 394.98488883569223, 396.142351808335, 400.19161294185744, 4599.747013914233, 4942.728149951751, 5140.742962851861, 5472.972635136824, 5568.49883510484, 4289.6997210195295, 4652.441706995157, 4695.929561056587, 4722.527747225285, 5452.40023597404, 4076.7961601919933, 4100.73991177015, 4133.016152246923, 4270.572942705736, 4444.066745983348, 3363.275746645213, 3858.961410385902, 4061.887818334464, 4080.877758616389, 4466.292976829224, 4437.889052773674, 4474.664400169984, 4475.731456112628, 4477.462704475468, 4511.842085527007, 3526.5239192708914, 4345.263073756567, 4431.933520997171, 4458.777061146962, 4459.710118842298, 3544.85820567177, 4070.3080476319096, 4084.7125609658688, 4109.609587089221, 4147.4815648044, 4131.793410329502, 4752.334673145728, 4791.053502096221, 4893.0, 5252.789888404459, 4409.757463339515, 4655.301704744282, 5027.773750181214, 5105.897882042375, 5226.7909283628605, 4541.500434952152, 5249.795086063862, 5723.198752174699, 5726.0, 5780.450857168562, 4053.3717273822604, 4080.5511393746665, 4356.172327257811, 4533.387992620987, 4573.839915602971, 4698.788554515021, 5459.044667183227, 6008.519318454513, 6020.96989515054, 6025.674351642631, 4728.645351598575, 4754.881127971759, 4758.262469317261, 4784.234522476387, 4827.0, 4121.773302468393, 4312.7627980461375, 4512.72923624577, 4831.782569784368, 4858.514148585114, 4157.958420415757, 4744.905101897943, 4802.309263522737, 5196.53231209193, 5218.321618189687, 5206.229881840192, 5352.759125839347, 5509.917351239735, 5671.901396483245, 5683.886322273532, 1640.1307307127242, 1641.0809946430038, 1644.6464010237617, 1652.8264532223943, 1658.7677725118492, 1000.7848312612823, 1084.1110289562482, 1118.9160812939062, 1147.1167201254987, 1198.4966314148078, 4628.3288923105965, 4891.486393928657, 4974.9751251243515, 5256.898429926362, 5463.508284254436, 4676.485586585472, 5220.903610241816, 5364.651297665718, 5776.451237132465, 5982.700864956735, 4403.493598236244, 4731.716097034189, 4927.630427717935, 5062.772584891583, 5079.568236699922, 2469.5678256305073, 2840.0343883079854, 2917.0, 2951.1441681912434, 3207.935841283208, 5140.845774626767, 5153.896922061538, 5510.448955104457, 5713.457221563945, 6176.66028368435, 5325.920111198335, 5380.085385484477, 5894.6757928314355, 6143.32423433422, 6242.09489624002, 5513.69931453467, 5980.910286345708, 6013.879722405527, 6093.512518998381, 6198.72105755233, 5019.745063734066, 5399.811006614749, 5680.6875621839545, 6013.458788709037, 6143.56995010345, 5219.660722053054, 5235.528802407802, 5500.257465242301, 5794.3626201117, 5870.530357571299, 5712.457316554921, 6205.0, 6207.099970504161, 6207.224096987877, 6277.121203031488, 3884.6309600587897, 4178.811953462162, 4307.418498502662, 4512.9774351128035, 4675.143007019291, 4887.755612219304, 5088.338515992898, 5444.401115877329, 5866.826634673168, 6018.668973206516, 4873.805047798049, 5042.0, 5398.676079435247, 5905.734241959208, 5917.911231331447, 4399.978000109979, 5712.685802280748, 5859.648421094748, 5950.7917222896995, 5974.2830860297045, 5139.717315547681, 5210.713410762292, 5711.428857114422, 5711.5145212655725, 5717.285339332583, 4841.661083724105, 5408.971635636636, 5526.668399896019, 5545.61359660085, 5850.94149058521, 4840.34655321527, 4892.319552476265, 4905.386826646669, 5278.683279003272, 5613.961417137843], "value": 4286.473653227207, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.5162718419438, 788.8580055589991, 807.3057170833079, 866.3199388480042, 875.7722992022073, 14070.718585628281, 15911.886167968232, 17235.224414901328, 17260.309587616495, 17293.65412691747, 15979.600509987227, 16095.0, 16118.355265789383, 16166.75749863753, 16181.35274589015, 971.4851128901659, 972.2125078686269, 1013.9949300253526, 1016.3038318751642, 1027.2193133218732, 1020.7397113735987, 1029.0532709906904, 1029.4441001858986, 1029.67050543826, 1030.6753372687617, 1064.4943651765388, 1065.398050101694, 1067.5836423794726, 1068.4978060311641, 1069.7486090768664, 1049.218332342406, 1055.3615062886959, 1063.3141623652778, 1063.7074804428767, 1065.110632621758, 786.0999155966387, 789.3921680306189, 790.4071946040469, 790.6560646118957, 792.2157064506155, 16412.09733464659, 16929.0, 17480.562985925328, 17562.594992400576, 17644.382446614305, 12453.93773031129, 14400.423983040771, 14936.850631493655, 15081.0, 15330.15684137372, 11949.402529873472, 12119.272843629411, 12238.143329966906, 12249.816252756216, 12524.311162886037, 3834.6740527055235, 3835.9424608630893, 3862.7013164474206, 3872.5159355080614, 3932.3708206686792, 115814.0, 116466.0, 119307.0, 120659.99999999913, 121468.17797733042, 11315.20793544444, 11387.943060284812, 11652.53389864396, 11879.881201187878, 12270.938645306716, 23968.64047039296, 24374.0, 24633.753662463147, 24649.383765405648, 24662.630060549105, 7989.920100799034, 8053.476524025919, 8317.95841020791, 8392.832143357098, 8491.150884911583, 4558.635309175324, 4599.862004139881, 4657.510961349078, 4665.533446655375, 4698.2247929092, 3473.583170019614, 3590.3537363274863, 3616.023673608111, 3723.422869455239, 3864.4589757433982, 710.4884483172108, 711.4095300900344, 716.2586722742042, 734.9485536012535, 736.9130532464571, 652.6927311952226, 664.6875968294894, 675.9493038022168, 682.4983637026781, 686.4474098350776, 10704.771615211948, 11208.383538905438, 11802.454664209698, 11815.822762658567, 11888.227265227733, 593.2910172344008, 618.6875627807929, 623.2769986815273, 624.0202881476039, 744.9515781474187, 4941.777620007037, 5022.59819214462, 5247.265382846328, 5252.5535329497425, 5261.815836445706, 88268.0, 97580.0241997571, 98011.50994244983, 100773.0, 107621.0, 61827.38172618216, 80313.59843200746, 82141.58929205436, 85801.0, 94705.0, 69035.0, 75999.0, 78169.60915195501, 83680.32639347178, 84620.30759384779, 11185.608503702331, 11197.832032519356, 11202.775944481227, 11354.091672666002, 11541.711457213301, 40125.59874401218, 49413.75293123511, 49591.0, 49969.0, 50932.98134037373, 63014.0, 66235.00647490195, 66382.0, 67080.0, 67355.65288694296, 12742.872571274169, 12924.159929604546, 13188.604341869759, 13223.471061157446, 13276.137051091648, 14914.627134321729, 14950.729188018706, 15191.696166076837, 15338.818249813014, 16000.519984400487, 13682.31588420555, 14379.0, 14397.208153551655, 14453.638659033608, 14554.27228638585, 12978.286194259406, 12997.935010325265, 13298.00264980111, 13983.930080349533, 14042.227677477837, 13376.732465350833, 13393.598192054254, 13845.030847840555, 13853.445862165401, 13903.652408689864, 9980.800383992424, 10407.167426606005, 10519.26365154432, 10547.367157970546, 10944.39805810688, 9308.83639545057, 11266.887331126583, 11352.716182095513, 11446.198766086627, 11452.312861228353], "value": 7963.2063831728055, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 119.0, 129.0, 174.0, 132.0, 96.0, 159.0, 114.0, 168.0, 179.0], "value": 137.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 112.0, 109.0, 140.0, 110.0, 116.0, 142.0, 140.0, 129.0, 111.0], "value": 124.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [261.0, 192.0, 197.0, 209.0, 196.0, 199.0, 196.0, 192.0, 195.0, 195.0], "value": 203.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 61.0, 84.0, 61.0, 60.0, 60.0, 63.0, 84.0, 86.0], "value": 69.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 73.0, 106.0, 102.0, 104.0, 101.0, 116.0, 69.0, 69.0, 81.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 89.0, 89.0, 88.0, 95.0, 131.0, 88.0, 90.0, 90.0, 86.0], "value": 98.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 96.0, 96.0, 96.0, 96.0, 118.0, 96.0, 96.0, 96.0, 95.0], "value": 100.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 100.0, 92.0, 91.0, 92.0, 92.0, 81.0, 143.0, 142.0, 92.0], "value": 101.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 79.0, 70.0, 69.0, 67.0, 68.0, 68.0, 79.0], "value": 70.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 65.0, 63.0, 67.0, 62.0, 57.0, 57.0, 75.0, 54.0, 64.0], "value": 62.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 82.0, 82.0, 83.0, 80.0, 86.0, 84.0, 83.0, 90.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 138.0, 137.0, 134.0, 135.0, 163.0, 155.0, 156.0, 206.0, 183.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 160.0, 224.0, 231.0, 202.0, 165.0, 167.0, 161.0, 164.0, 167.0], "value": 180.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 64.0, 65.0, 69.0, 76.0, 80.0, 85.0, 83.0], "value": 71.6, "unit": "score"}]}]} 18:44:05 INFO - Return code: 0 18:44:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:44:05 ERROR - # TBPL SUCCESS # 18:44:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:44:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:44:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:44:05 INFO - 2017-05-22 18:44:05.686 screenresolution[1666:10796] starting screenresolution argv=screenresolution get 18:44:05 INFO - 2017-05-22 18:44:05.689 screenresolution[1666:10796] Display 0: 1600x1200x32@60 18:44:05 INFO - 2017-05-22 18:44:05.695 screenresolution[1667:10800] starting screenresolution argv=screenresolution list 18:44:05 INFO - Available Modes on Display 0 18:44:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:44:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:44:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:44:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:44:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:44:05 INFO - Intel Iris: 18:44:05 INFO - Chipset Model: Intel Iris 18:44:05 INFO - Type: GPU 18:44:05 INFO - Bus: Built-In 18:44:05 INFO - VRAM (Dynamic, Max): 1536 MB 18:44:05 INFO - Vendor: Intel (0x8086) 18:44:05 INFO - Device ID: 0x0a2e 18:44:05 INFO - Revision ID: 0x0009 18:44:05 INFO - Displays: 18:44:05 INFO - Display: 18:44:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:44:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:44:05 INFO - Display Serial Number: 1600x1200 60 18:44:05 INFO - Main Display: Yes 18:44:05 INFO - Mirror: Off 18:44:05 INFO - Online: Yes 18:44:05 INFO - Rotation: Supported 18:44:05 INFO - Return code: 0 18:44:05 INFO - Running post-action listener: _resource_record_post_action 18:44:05 INFO - [mozharness: 2017-05-23 01:44:05.934154Z] Finished run-tests step (success) 18:44:05 INFO - Running post-run listener: _resource_record_post_run 18:44:05 INFO - Total resource usage - Wall time: 1191s; CPU: 54.0%; Read bytes: 40011776; Write bytes: 426143744; Read time: 252; Write time: 1447 18:44:05 INFO - TinderboxPrint: CPU usage
54.3% 18:44:05 INFO - TinderboxPrint: I/O read bytes / time
40,011,776 / 252 18:44:05 INFO - TinderboxPrint: I/O write bytes / time
426,143,744 / 1,447 18:44:05 INFO - TinderboxPrint: CPU idle
2,176.7 (45.7%) 18:44:05 INFO - TinderboxPrint: CPU system
952.3 (20.0%) 18:44:05 INFO - TinderboxPrint: CPU user
1,636.8 (34.3%) 18:44:05 INFO - TinderboxPrint: Swap in / out
162,152,448 / 0 18:44:05 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121936384; Write bytes: 118134784; Read time: 11080; Write time: 265 18:44:06 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 37865472; Write bytes: 298924032; Read time: 230; Write time: 1149 18:44:06 INFO - Running post-run listener: _upload_blobber_files 18:44:06 INFO - Blob upload gear active. 18:44:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:44:06 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 18:44:06 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'] 18:44:06 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 18:44:06 INFO - (blobuploader) - INFO - Open directory for files ... 18:44:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:44:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:44:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:08 INFO - (blobuploader) - INFO - Done attempting. 18:44:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:44:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:44:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:08 INFO - (blobuploader) - INFO - Done attempting. 18:44:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:44:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:44:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:10 INFO - (blobuploader) - INFO - Done attempting. 18:44:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:44:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:44:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:12 INFO - (blobuploader) - INFO - Done attempting. 18:44:12 INFO - (blobuploader) - INFO - Iteration through files over. 18:44:12 INFO - Return code: 0 18:44:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:44:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:44:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/41d8f26311ddde7e05afe7203a785bc10613f720ff4928dce1cbcddba7694eabc32a13c72596dce7c1fe1c0180a09e3d3e48d4ee54bc9e81098b3abf94d9383d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6b3c45f67f11f4c1bad1000005bbdbcffab9c570a1a294361f170848cefe16fb5e80c9024afb885b241a5ecc4a30c51ccb5f80b56d9b32e2057504f8a223dae7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/328a937f5a11278202a6bc23ff381ea1a812ea2e9bd51cefd00412d3442ab1a6c0ece982c82599bd7ccbb6c4b2552310274ac1eaea1958a39e6f2e4ad1d8e371", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f8218d27f8e340ef3c3e941b784950fc10296ad72eab0cd3d8b1f4e570d108d92e1fe94a494f8fe4d6211ed0a7b8ebe5fe41b4b4c4697c14d80f3b5c1358be62"} 18:44:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:44:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:44:12 INFO - Contents: 18:44:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/41d8f26311ddde7e05afe7203a785bc10613f720ff4928dce1cbcddba7694eabc32a13c72596dce7c1fe1c0180a09e3d3e48d4ee54bc9e81098b3abf94d9383d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6b3c45f67f11f4c1bad1000005bbdbcffab9c570a1a294361f170848cefe16fb5e80c9024afb885b241a5ecc4a30c51ccb5f80b56d9b32e2057504f8a223dae7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/328a937f5a11278202a6bc23ff381ea1a812ea2e9bd51cefd00412d3442ab1a6c0ece982c82599bd7ccbb6c4b2552310274ac1eaea1958a39e6f2e4ad1d8e371", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f8218d27f8e340ef3c3e941b784950fc10296ad72eab0cd3d8b1f4e570d108d92e1fe94a494f8fe4d6211ed0a7b8ebe5fe41b4b4c4697c14d80f3b5c1358be62"} 18:44:12 INFO - Running post-run listener: copy_logs_to_upload_dir 18:44:12 INFO - Copying logs to upload dir... 18:44:12 INFO - mkdir: /builds/slave/test/build/upload/logs 18:44:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.317477 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-22 18:44:12.735011) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 18:44:12.738673) ========= 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.SkFthDlezz/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.nXdDxA5ZCn/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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/41d8f26311ddde7e05afe7203a785bc10613f720ff4928dce1cbcddba7694eabc32a13c72596dce7c1fe1c0180a09e3d3e48d4ee54bc9e81098b3abf94d9383d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6b3c45f67f11f4c1bad1000005bbdbcffab9c570a1a294361f170848cefe16fb5e80c9024afb885b241a5ecc4a30c51ccb5f80b56d9b32e2057504f8a223dae7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/328a937f5a11278202a6bc23ff381ea1a812ea2e9bd51cefd00412d3442ab1a6c0ece982c82599bd7ccbb6c4b2552310274ac1eaea1958a39e6f2e4ad1d8e371", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f8218d27f8e340ef3c3e941b784950fc10296ad72eab0cd3d8b1f4e570d108d92e1fe94a494f8fe4d6211ed0a7b8ebe5fe41b4b4c4697c14d80f3b5c1358be62"} build_url:https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010957 build_url: 'https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/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/41d8f26311ddde7e05afe7203a785bc10613f720ff4928dce1cbcddba7694eabc32a13c72596dce7c1fe1c0180a09e3d3e48d4ee54bc9e81098b3abf94d9383d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6b3c45f67f11f4c1bad1000005bbdbcffab9c570a1a294361f170848cefe16fb5e80c9024afb885b241a5ecc4a30c51ccb5f80b56d9b32e2057504f8a223dae7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/328a937f5a11278202a6bc23ff381ea1a812ea2e9bd51cefd00412d3442ab1a6c0ece982c82599bd7ccbb6c4b2552310274ac1eaea1958a39e6f2e4ad1d8e371", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f8218d27f8e340ef3c3e941b784950fc10296ad72eab0cd3d8b1f4e570d108d92e1fe94a494f8fe4d6211ed0a7b8ebe5fe41b4b4c4697c14d80f3b5c1358be62"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 18:44:12.773270) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:44:12.773972) ========= 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.SkFthDlezz/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.nXdDxA5ZCn/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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.005372 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:44:12.823816) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 18:44:12.824149) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 18:44:12.824509) ========= ========= Total master_lag: 0.17 =========