builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0017 starttime: 1495734768.04 results: success (0) buildid: 20170525093444 builduid: 6fec87d987904ce5b699a3cbd650c88f revision: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 10:52:48.044695) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 10:52:48.045137) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 10:52:48.045409) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 10:52:48.071068) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:52:48.071422) ========= 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.NM3AyOhCc4/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.Q1fu17si7P/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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-25 10:52:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 40.2M=0s 2017-05-25 10:52:48 (40.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.314441 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:52:48.409151) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:52:48.409477) ========= 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.NM3AyOhCc4/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.Q1fu17si7P/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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.035737 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:52:48.465730) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:52:48.466065) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d3ce3e3812c9372a12b6416f0ceedd2e768b5277 --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 d3ce3e3812c9372a12b6416f0ceedd2e768b5277 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NM3AyOhCc4/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.Q1fu17si7P/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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-25 10:52:48,565 truncating revision to first 12 chars 2017-05-25 10:52:48,566 Setting DEBUG logging. 2017-05-25 10:52:48,566 attempt 1/10 2017-05-25 10:52:48,566 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d3ce3e3812c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 10:52:48,886 unpacking tar archive at: mozilla-beta-d3ce3e3812c9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.595914 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:52:49.088975) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 10:52:49.089792) ========= script_repo_revision: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 10:52:49.090218) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 10:52:49.090485) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 10:52:49.109923) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-05-25 10:52:49.110297) ========= /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.NM3AyOhCc4/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.Q1fu17si7P/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 10:52:49 INFO - MultiFileLogger online at 20170525 10:52:49 in /builds/slave/test 10:52:49 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 10:52:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:52:49 INFO - {'append_to_log': False, 10:52:49 INFO - 'base_work_dir': '/builds/slave/test', 10:52:49 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:52:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:52:49 INFO - 'branch': 'Mozilla-Beta', 10:52:49 INFO - 'buildbot_json_path': 'buildprops.json', 10:52:49 INFO - 'config_files': ('talos/mac_config.py',), 10:52:49 INFO - 'default_actions': ('clobber', 10:52:49 INFO - 'read-buildbot-config', 10:52:49 INFO - 'download-and-extract', 10:52:49 INFO - 'populate-webroot', 10:52:49 INFO - 'create-virtualenv', 10:52:49 INFO - 'install', 10:52:49 INFO - 'run-tests'), 10:52:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:52:49 INFO - 'download_minidump_stackwalk': True, 10:52:49 INFO - 'download_symbols': 'ondemand', 10:52:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:52:49 INFO - 'tooltool.py': '/tools/tooltool.py', 10:52:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:52:49 INFO - '/tools/misc-python/virtualenv.py')}, 10:52:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:52:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:52:49 INFO - 'gecko_profile': False, 10:52:49 INFO - 'gecko_profile_interval': 0, 10:52:49 INFO - 'installer_path': 'installer.exe', 10:52:49 INFO - 'log_level': 'info', 10:52:49 INFO - 'log_name': 'talos', 10:52:49 INFO - 'log_to_console': True, 10:52:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:52:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:52:49 INFO - 'opt_config_files': (), 10:52:49 INFO - 'pip_index': False, 10:52:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:52:49 INFO - 'cmd': ('bash', 10:52:49 INFO - '-c', 10:52:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:52:49 INFO - 'enabled': True, 10:52:49 INFO - 'halt_on_failure': False, 10:52:49 INFO - 'name': 'check_screen_resolution'},), 10:52:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:52:49 INFO - 'cmd': ('bash', 10:52:49 INFO - '-c', 10:52:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:52:49 INFO - 'enabled': True, 10:52:49 INFO - 'halt_on_failure': False, 10:52:49 INFO - 'name': 'check_screen_resolution'},), 10:52:49 INFO - 'run_cmd_checks_enabled': True, 10:52:49 INFO - 'suite': 'dromaeojs-e10s', 10:52:49 INFO - 'system_bits': '32', 10:52:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:52:49 INFO - 'title': 't-yosemite-r7-0017', 10:52:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:52:49 INFO - 'use_talos_json': True, 10:52:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:52:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:52:49 INFO - 'work_dir': 'build'} 10:52:49 INFO - [mozharness: 2017-05-25 17:52:49.319295Z] Running clobber step. 10:52:49 INFO - Running pre-action listener: _resource_record_pre_action 10:52:49 INFO - Running main action method: clobber 10:52:49 INFO - rmtree: /builds/slave/test/build 10:52:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:52:51 INFO - Running post-action listener: _resource_record_post_action 10:52:51 INFO - [mozharness: 2017-05-25 17:52:51.661595Z] Finished clobber step (success) 10:52:51 INFO - [mozharness: 2017-05-25 17:52:51.661717Z] Running read-buildbot-config step. 10:52:51 INFO - Running pre-action listener: _resource_record_pre_action 10:52:51 INFO - Running main action method: read_buildbot_config 10:52:51 INFO - Using buildbot properties: 10:52:51 INFO - { 10:52:51 INFO - "project": "", 10:52:51 INFO - "product": "firefox", 10:52:51 INFO - "script_repo_revision": "production", 10:52:51 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 10:52:51 INFO - "repository": "", 10:52:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 10:52:51 INFO - "buildid": "20170525093444", 10:52:51 INFO - "pgo_build": "False", 10:52:51 INFO - "basedir": "/builds/slave/test", 10:52:51 INFO - "buildnumber": 1, 10:52:51 INFO - "slavename": "t-yosemite-r7-0017", 10:52:51 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:52:51 INFO - "platform": "yosemite_r7", 10:52:51 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 10:52:51 INFO - "branch": "mozilla-beta", 10:52:51 INFO - "repo_path": "releases/mozilla-beta", 10:52:51 INFO - "revision": "d3ce3e3812c9372a12b6416f0ceedd2e768b5277", 10:52:51 INFO - "stage_platform": "macosx64", 10:52:51 INFO - "builduid": "6fec87d987904ce5b699a3cbd650c88f", 10:52:51 INFO - "slavebuilddir": "test" 10:52:51 INFO - } 10:52:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 10:52:51 INFO - Running post-action listener: _resource_record_post_action 10:52:51 INFO - [mozharness: 2017-05-25 17:52:51.665110Z] Finished read-buildbot-config step (success) 10:52:51 INFO - [mozharness: 2017-05-25 17:52:51.665250Z] Running download-and-extract step. 10:52:51 INFO - Running pre-action listener: _resource_record_pre_action 10:52:51 INFO - Running main action method: download_and_extract 10:52:51 INFO - mkdir: /builds/slave/test/build/tests 10:52:51 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')]} 10:52:51 INFO - trying https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 10:52:51 INFO - Downloading https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/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 10:52:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/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 10:52:52 INFO - Downloaded 1287 bytes. 10:52:52 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:52:52 INFO - Using the following test package requirements: 10:52:52 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:52:52 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 10:52:52 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:52:52 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:52:52 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 10:52:52 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:52:52 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 10:52:52 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:52:52 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:52:52 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 10:52:52 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:52:52 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:52:52 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 10:52:52 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:52:52 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 10:52:52 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:52:52 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 10:52:52 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:52:52 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 10:52:52 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 10:52:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:52:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:52:52 INFO - Fetch https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:52:53 INFO - Expected file size: 51396529 10:52:53 INFO - Obtained file size: 51396529 10:53:09 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 10:53:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:53:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:53:09 INFO - Fetch https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:53:11 INFO - Expected file size: 51396529 10:53:11 INFO - Obtained file size: 51396529 10:53:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 10:53:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 10:53:26 INFO - Fetch https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 10:53:27 INFO - Expected file size: 14404449 10:53:27 INFO - Obtained file size: 14404449 10:53:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:27 INFO - trying https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:53:27 INFO - Downloading https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 10:53:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/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 10:53:29 INFO - Downloaded 59647465 bytes. 10:53:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:53:29 INFO - mkdir: /builds/slave/test/properties 10:53:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:53:29 INFO - Writing to file /builds/slave/test/properties/build_url 10:53:29 INFO - Contents: 10:53:29 INFO - build_url:https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:53:30 INFO - Running post-action listener: _resource_record_post_action 10:53:30 INFO - Running post-action listener: set_extra_try_arguments 10:53:30 INFO - [mozharness: 2017-05-25 17:53:30.066210Z] Finished download-and-extract step (success) 10:53:30 INFO - [mozharness: 2017-05-25 17:53:30.066331Z] Running populate-webroot step. 10:53:30 INFO - Running pre-action listener: _resource_record_pre_action 10:53:30 INFO - Running main action method: populate_webroot 10:53:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:53:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:53:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:53:30 INFO - '--tppagecycles', 10:53:30 INFO - '7'], 10:53:30 INFO - 'tests': ['tsvgm']}}, 10:53:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:53:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:53:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:53:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:53:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:53:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:53:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:53:30 INFO - 'talos_options': ['--disable-e10s'], 10:53:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:53:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:53:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:53:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:53:30 INFO - 'talos_options': ['--disable-e10s'], 10:53:30 INFO - 'tests': ['damp', 'tps']}, 10:53:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:53:30 INFO - 'tests': ['damp', 'tps']}, 10:53:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:53:30 INFO - 'tests': ['dromaeo_dom']}, 10:53:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:53:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:53:30 INFO - 'tests': ['basic_compositor_video']}, 10:53:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:53:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:53:30 INFO - 'tests': ['a11yr', 10:53:30 INFO - 'ts_paint', 10:53:30 INFO - 'tpaint', 10:53:30 INFO - 'sessionrestore', 10:53:30 INFO - 'sessionrestore_no_auto_restore', 10:53:30 INFO - 'tabpaint']}, 10:53:30 INFO - 'other-e10s': {'tests': ['a11yr', 10:53:30 INFO - 'ts_paint', 10:53:30 INFO - 'tpaint', 10:53:30 INFO - 'sessionrestore', 10:53:30 INFO - 'sessionrestore_no_auto_restore', 10:53:30 INFO - 'tabpaint']}, 10:53:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:53:30 INFO - 'ts_paint', 10:53:30 INFO - 'tpaint', 10:53:30 INFO - 'sessionrestore', 10:53:30 INFO - 'sessionrestore_no_auto_restore', 10:53:30 INFO - 'tabpaint']}, 10:53:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:53:30 INFO - 'ts_paint', 10:53:30 INFO - 'tpaint', 10:53:30 INFO - 'sessionrestore', 10:53:30 INFO - 'sessionrestore_no_auto_restore', 10:53:30 INFO - 'tabpaint']}, 10:53:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:53:30 INFO - 'tests': ['a11yr', 10:53:30 INFO - 'ts_paint', 10:53:30 INFO - 'tpaint', 10:53:30 INFO - 'sessionrestore', 10:53:30 INFO - 'sessionrestore_no_auto_restore', 10:53:30 INFO - 'tabpaint']}, 10:53:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:53:30 INFO - 'tests': ['a11yr', 10:53:30 INFO - 'ts_paint', 10:53:30 INFO - 'tpaint', 10:53:30 INFO - 'sessionrestore', 10:53:30 INFO - 'sessionrestore_no_auto_restore', 10:53:30 INFO - 'tabpaint']}, 10:53:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:53:30 INFO - 'tests': ['tsvgx', 10:53:30 INFO - 'tsvgr_opacity', 10:53:30 INFO - 'tart', 10:53:30 INFO - 'tscrollx', 10:53:30 INFO - 'cart', 10:53:30 INFO - 'tsvg_static']}, 10:53:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:53:30 INFO - 'tsvgr_opacity', 10:53:30 INFO - 'tart', 10:53:30 INFO - 'tscrollx', 10:53:30 INFO - 'cart', 10:53:30 INFO - 'tsvg_static']}, 10:53:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:53:30 INFO - 'talos_options': ['--disable-e10s'], 10:53:30 INFO - 'tests': ['tp5o']}, 10:53:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:53:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:53:30 INFO - 'talos_options': ['--disable-e10s', 10:53:30 INFO - '--xperf_path', 10:53:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:53:30 INFO - 'tests': ['tp5n']}, 10:53:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:53:30 INFO - 'talos_options': ['--xperf_path', 10:53:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:53:30 INFO - 'tests': ['tp5n']}}, 10:53:30 INFO - 'talos.zip': {'path': '', 10:53:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:53:30 INFO - Running post-action listener: _resource_record_post_action 10:53:30 INFO - [mozharness: 2017-05-25 17:53:30.084423Z] Finished populate-webroot step (success) 10:53:30 INFO - [mozharness: 2017-05-25 17:53:30.084555Z] Running create-virtualenv step. 10:53:30 INFO - Running pre-action listener: _resource_record_pre_action 10:53:30 INFO - Running main action method: create_virtualenv 10:53:30 INFO - Creating virtualenv /builds/slave/test/build/venv 10:53:30 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 10:53:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:53:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:53:30 INFO - Using real prefix '/tools/python27' 10:53:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:53:30 INFO - Installing distribute.............................................................................................................................................................................................done. 10:53:33 INFO - Installing pip.................done. 10:53:33 INFO - Return code: 0 10:53:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:53:34 INFO - Reading from file tmpfile_stdout 10:53:34 INFO - Output received: 10:53:34 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) 10:53:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109a2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe681572db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980bd30>, '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 0x109732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109800be8>, '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.NM3AyOhCc4/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.Q1fu17si7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 10:53:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:53:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:53:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NM3AyOhCc4/Render', 10:53:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:34 INFO - 'HOME': '/Users/cltbld', 10:53:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:34 INFO - 'LOGNAME': 'cltbld', 10:53:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:34 INFO - 'MOZ_NO_REMOTE': '1', 10:53:34 INFO - 'NO_EM_RESTART': '1', 10:53:34 INFO - 'PAGER': '/bin/cat', 10:53:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:34 INFO - 'PWD': '/builds/slave/test', 10:53:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:34 INFO - 'SHELL': '/bin/bash', 10:53:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q1fu17si7P/Listeners', 10:53:34 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 10:53:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:34 INFO - 'USER': 'cltbld', 10:53:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:34 INFO - 'XPC_FLAGS': '0x0', 10:53:34 INFO - 'XPC_SERVICE_NAME': '0', 10:53:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:34 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 10:53:34 INFO - Cleaning up... 10:53:34 INFO - Return code: 0 10:53:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109a2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe681572db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980bd30>, '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 0x109732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109800be8>, '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.NM3AyOhCc4/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.Q1fu17si7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 10:53:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:53:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:53:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NM3AyOhCc4/Render', 10:53:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:34 INFO - 'HOME': '/Users/cltbld', 10:53:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:34 INFO - 'LOGNAME': 'cltbld', 10:53:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:34 INFO - 'MOZ_NO_REMOTE': '1', 10:53:34 INFO - 'NO_EM_RESTART': '1', 10:53:34 INFO - 'PAGER': '/bin/cat', 10:53:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:34 INFO - 'PWD': '/builds/slave/test', 10:53:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:34 INFO - 'SHELL': '/bin/bash', 10:53:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q1fu17si7P/Listeners', 10:53:34 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 10:53:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:34 INFO - 'USER': 'cltbld', 10:53:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:34 INFO - 'XPC_FLAGS': '0x0', 10:53:34 INFO - 'XPC_SERVICE_NAME': '0', 10:53:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:34 INFO - Downloading/unpacking psutil>=3.1.1 10:53:34 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 10:53:34 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 10:53:34 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 10:53:34 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 10:53:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:53:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:53:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:53:36 INFO - Installing collected packages: psutil 10:53:36 INFO - Running setup.py install for psutil 10:53:36 INFO - building 'psutil._psutil_osx' extension 10:53:36 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 10:53:37 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 10:53:37 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 10:53:37 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 10:53:37 INFO - building 'psutil._psutil_posix' extension 10:53:37 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 10:53:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:53:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:53:37 INFO - ^ 10:53:37 INFO - 1 warning generated. 10:53:37 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 10:53:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:53:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:53:37 INFO - Successfully installed psutil 10:53:37 INFO - Cleaning up... 10:53:37 INFO - Return code: 0 10:53:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109a2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe681572db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980bd30>, '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 0x109732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109800be8>, '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.NM3AyOhCc4/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.Q1fu17si7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 10:53:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:53:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:53:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NM3AyOhCc4/Render', 10:53:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:37 INFO - 'HOME': '/Users/cltbld', 10:53:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:37 INFO - 'LOGNAME': 'cltbld', 10:53:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:37 INFO - 'MOZ_NO_REMOTE': '1', 10:53:37 INFO - 'NO_EM_RESTART': '1', 10:53:37 INFO - 'PAGER': '/bin/cat', 10:53:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:37 INFO - 'PWD': '/builds/slave/test', 10:53:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:37 INFO - 'SHELL': '/bin/bash', 10:53:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q1fu17si7P/Listeners', 10:53:37 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 10:53:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:37 INFO - 'USER': 'cltbld', 10:53:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:37 INFO - 'XPC_FLAGS': '0x0', 10:53:37 INFO - 'XPC_SERVICE_NAME': '0', 10:53:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:53:37 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 10:53:37 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 10:53:37 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 10:53:37 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 10:53:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:53:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:53:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:53:39 INFO - Installing collected packages: mozsystemmonitor 10:53:39 INFO - Running setup.py install for mozsystemmonitor 10:53:39 INFO - Successfully installed mozsystemmonitor 10:53:39 INFO - Cleaning up... 10:53:39 INFO - Return code: 0 10:53:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:53:39 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')]} 10:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:39 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')]} 10:53:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:39 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 0x109a2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe681572db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980bd30>, '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 0x109732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109800be8>, '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.NM3AyOhCc4/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.Q1fu17si7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 10:53:39 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 10:53:39 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 10:53:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NM3AyOhCc4/Render', 10:53:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:39 INFO - 'HOME': '/Users/cltbld', 10:53:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:39 INFO - 'LOGNAME': 'cltbld', 10:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:39 INFO - 'MOZ_NO_REMOTE': '1', 10:53:39 INFO - 'NO_EM_RESTART': '1', 10:53:39 INFO - 'PAGER': '/bin/cat', 10:53:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:39 INFO - 'PWD': '/builds/slave/test', 10:53:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:39 INFO - 'SHELL': '/bin/bash', 10:53:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q1fu17si7P/Listeners', 10:53:39 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 10:53:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:39 INFO - 'USER': 'cltbld', 10:53:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:39 INFO - 'XPC_FLAGS': '0x0', 10:53:39 INFO - 'XPC_SERVICE_NAME': '0', 10:53:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:39 INFO - Downloading/unpacking jsonschema==2.5.1 10:53:39 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 10:53:39 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 10:53:39 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 10:53:39 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 10:53:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:53:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:53:41 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 10:53:41 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 10:53:41 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 10:53:41 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 10:53:41 INFO - Downloading functools32-3.2.3-2.tar.gz 10:53:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:53:41 INFO - warning: no files found matching '*.txt' 10:53:41 INFO - no previously-included directories found matching 'build' 10:53:41 INFO - no previously-included directories found matching 'dist' 10:53:41 INFO - no previously-included directories found matching '.git*' 10:53:41 INFO - Installing collected packages: jsonschema, functools32 10:53:41 INFO - Running setup.py install for functools32 10:53:41 INFO - warning: no files found matching '*.txt' 10:53:41 INFO - no previously-included directories found matching 'build' 10:53:41 INFO - no previously-included directories found matching 'dist' 10:53:41 INFO - no previously-included directories found matching '.git*' 10:53:41 INFO - Successfully installed jsonschema functools32 10:53:41 INFO - Cleaning up... 10:53:41 INFO - Return code: 0 10:53:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:53:41 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')]} 10:53:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:41 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')]} 10:53:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:41 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 0x109a2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe681572db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980bd30>, '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 0x109732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109800be8>, '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.NM3AyOhCc4/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.Q1fu17si7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 10:53:41 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 10:53:41 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 10:53:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NM3AyOhCc4/Render', 10:53:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:41 INFO - 'HOME': '/Users/cltbld', 10:53:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:41 INFO - 'LOGNAME': 'cltbld', 10:53:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:41 INFO - 'MOZ_NO_REMOTE': '1', 10:53:41 INFO - 'NO_EM_RESTART': '1', 10:53:41 INFO - 'PAGER': '/bin/cat', 10:53:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:41 INFO - 'PWD': '/builds/slave/test', 10:53:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:41 INFO - 'SHELL': '/bin/bash', 10:53:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q1fu17si7P/Listeners', 10:53:41 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 10:53:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:41 INFO - 'USER': 'cltbld', 10:53:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:41 INFO - 'XPC_FLAGS': '0x0', 10:53:41 INFO - 'XPC_SERVICE_NAME': '0', 10:53:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:53:42 INFO - Cleaning up... 10:53:42 INFO - Return code: 0 10:53:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:53:42 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')]} 10:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:42 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')]} 10:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:42 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 0x109a2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe681572db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980bd30>, '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 0x109732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109800be8>, '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.NM3AyOhCc4/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.Q1fu17si7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 10:53:42 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 10:53:42 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 10:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NM3AyOhCc4/Render', 10:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:42 INFO - 'HOME': '/Users/cltbld', 10:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:42 INFO - 'LOGNAME': 'cltbld', 10:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:42 INFO - 'MOZ_NO_REMOTE': '1', 10:53:42 INFO - 'NO_EM_RESTART': '1', 10:53:42 INFO - 'PAGER': '/bin/cat', 10:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:42 INFO - 'PWD': '/builds/slave/test', 10:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:42 INFO - 'SHELL': '/bin/bash', 10:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q1fu17si7P/Listeners', 10:53:42 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 10:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:42 INFO - 'USER': 'cltbld', 10:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:42 INFO - 'XPC_FLAGS': '0x0', 10:53:42 INFO - 'XPC_SERVICE_NAME': '0', 10:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:42 INFO - Downloading/unpacking blobuploader==1.2.4 10:53:42 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 10:53:42 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 10:53:42 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 10:53:42 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 10:53:43 INFO - Downloading blobuploader-1.2.4.tar.gz 10:53:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:53:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:53:43 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 10:53:43 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 10:53:43 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 10:53:43 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 10:53:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:53:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:53:44 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 10:53:44 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 10:53:44 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 10:53:44 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 10:53:44 INFO - Downloading docopt-0.6.1.tar.gz 10:53:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:53:44 INFO - Installing collected packages: blobuploader, requests, docopt 10:53:44 INFO - Running setup.py install for blobuploader 10:53:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:53:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:53:44 INFO - Running setup.py install for requests 10:53:45 INFO - Running setup.py install for docopt 10:53:45 INFO - Successfully installed blobuploader requests docopt 10:53:45 INFO - Cleaning up... 10:53:45 INFO - Return code: 0 10:53:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53: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')]} 10:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:46 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')]} 10:53:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:46 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 0x109a2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe681572db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980bd30>, '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 0x109732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109800be8>, '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.NM3AyOhCc4/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.Q1fu17si7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 10:53:46 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 10:53:46 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 10:53:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NM3AyOhCc4/Render', 10:53:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:46 INFO - 'HOME': '/Users/cltbld', 10:53:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:46 INFO - 'LOGNAME': 'cltbld', 10:53:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:46 INFO - 'MOZ_NO_REMOTE': '1', 10:53:46 INFO - 'NO_EM_RESTART': '1', 10:53:46 INFO - 'PAGER': '/bin/cat', 10:53:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:46 INFO - 'PWD': '/builds/slave/test', 10:53:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:46 INFO - 'SHELL': '/bin/bash', 10:53:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q1fu17si7P/Listeners', 10:53:46 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 10:53:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:46 INFO - 'USER': 'cltbld', 10:53:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:46 INFO - 'XPC_FLAGS': '0x0', 10:53:46 INFO - 'XPC_SERVICE_NAME': '0', 10:53:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:53:46 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-iS8BU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:53:46 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-GKkrAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:53:46 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-sh_9SG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:53:47 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-iNtLpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:53:47 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-EUFtlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:53:47 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-WKgWQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:53:47 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-VvSu5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:53:47 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-gVTsdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:53:47 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-levR_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:53:47 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Df1zUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:53:47 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-xmOTBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:53:47 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-CfssLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:53:47 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-q8Nq5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:53:48 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-OYabmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:48 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-mz7tbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:53:48 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-JsQOni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:53:48 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-IUyBXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:53:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:53:48 INFO - Running setup.py install for manifestparser 10:53:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:53:48 INFO - Running setup.py install for mozcrash 10:53:48 INFO - Running setup.py install for mozdebug 10:53:48 INFO - Running setup.py install for mozdevice 10:53:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:53:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:53:49 INFO - Running setup.py install for mozfile 10:53:49 INFO - Running setup.py install for mozhttpd 10:53:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:53:49 INFO - Running setup.py install for mozinfo 10:53:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:53:49 INFO - Running setup.py install for mozInstall 10:53:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:53:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:53:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:53:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:53:49 INFO - Running setup.py install for mozleak 10:53:49 INFO - Running setup.py install for mozlog 10:53:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:53:49 INFO - Running setup.py install for moznetwork 10:53:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:53:50 INFO - Running setup.py install for mozprocess 10:53:50 INFO - Running setup.py install for mozprofile 10:53:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:53:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:53:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:53:50 INFO - Running setup.py install for mozrunner 10:53:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:53:50 INFO - Running setup.py install for mozscreenshot 10:53:50 INFO - Running setup.py install for moztest 10:53:50 INFO - Running setup.py install for mozversion 10:53:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:53:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:53:51 INFO - Cleaning up... 10:53:51 INFO - Return code: 0 10:53:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53:51 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')]} 10:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:51 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')]} 10:53:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:51 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 0x109a2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe681572db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980bd30>, '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 0x109732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109800be8>, '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.NM3AyOhCc4/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.Q1fu17si7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 10:53:51 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 10:53:51 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 10:53:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NM3AyOhCc4/Render', 10:53:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:51 INFO - 'HOME': '/Users/cltbld', 10:53:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:51 INFO - 'LOGNAME': 'cltbld', 10:53:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:51 INFO - 'MOZ_NO_REMOTE': '1', 10:53:51 INFO - 'NO_EM_RESTART': '1', 10:53:51 INFO - 'PAGER': '/bin/cat', 10:53:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:51 INFO - 'PWD': '/builds/slave/test', 10:53:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:51 INFO - 'SHELL': '/bin/bash', 10:53:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q1fu17si7P/Listeners', 10:53:51 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 10:53:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:51 INFO - 'USER': 'cltbld', 10:53:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:51 INFO - 'XPC_FLAGS': '0x0', 10:53:51 INFO - 'XPC_SERVICE_NAME': '0', 10:53:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:53:51 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-VG_tGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:53:51 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)) 10:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:53:51 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-1YXzk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:53:51 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)) 10:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:53:51 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-JNR1LD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:53:51 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)) 10:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:53:51 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-mblA2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:53:52 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)) 10:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:53:52 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-wYLlkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:53:52 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)) 10:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:53:52 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-uz40tq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:53:52 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)) 10:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:53:52 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-EnWLUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:53:52 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)) 10:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:53:52 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-d5HmQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:53:52 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)) 10:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:53:52 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-EmoSKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:53:52 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)) 10:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:53:52 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-nndGdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:53:52 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)) 10:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:53:52 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-DNBHTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:53:52 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)) 10:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:53:52 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-aDl9RN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:53:52 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)) 10:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:53:52 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-OffaEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:53:53 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)) 10:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:53:53 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-kcXOX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:53:53 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)) 10:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:53 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-GsBlHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:53 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)) 10:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:53:53 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-FCVL8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:53:53 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)) 10:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:53:53 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-oQCtmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:53:53 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)) 10:53:53 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)) 10:53:53 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)) 10:53:53 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)) 10:53:53 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)) 10:53:53 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)) 10:53:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:53:53 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 10:53:53 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 10:53:53 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 10:53:53 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 10:53:55 INFO - Downloading blessings-1.6.tar.gz 10:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:53:55 INFO - Installing collected packages: blessings 10:53:55 INFO - Running setup.py install for blessings 10:53:55 INFO - Successfully installed blessings 10:53:55 INFO - Cleaning up... 10:53:55 INFO - Return code: 0 10:53:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:53:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:53:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:53:55 INFO - Reading from file tmpfile_stdout 10:53:55 INFO - Current package versions: 10:53:55 INFO - blessings == 1.6 10:53:55 INFO - blobuploader == 1.2.4 10:53:55 INFO - docopt == 0.6.1 10:53:55 INFO - functools32 == 3.2.3-2 10:53:55 INFO - jsonschema == 2.5.1 10:53:55 INFO - manifestparser == 1.1 10:53:55 INFO - mozInstall == 1.12 10:53:55 INFO - mozcrash == 1.0 10:53:55 INFO - mozdebug == 0.1 10:53:55 INFO - mozdevice == 0.49 10:53:55 INFO - mozfile == 1.2 10:53:55 INFO - mozhttpd == 0.7 10:53:55 INFO - mozinfo == 0.9 10:53:55 INFO - mozleak == 0.1 10:53:55 INFO - mozlog == 3.4 10:53:55 INFO - moznetwork == 0.27 10:53:55 INFO - mozprocess == 0.25 10:53:55 INFO - mozprofile == 0.28 10:53:55 INFO - mozrunner == 6.13 10:53:55 INFO - mozscreenshot == 0.1 10:53:55 INFO - mozsystemmonitor == 0.3 10:53:55 INFO - moztest == 0.8 10:53:55 INFO - mozversion == 1.4 10:53:55 INFO - psutil == 3.1.1 10:53:55 INFO - requests == 1.2.3 10:53:55 INFO - wsgiref == 0.1.2 10:53:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53: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')]} 10:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53: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')]} 10:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:55 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 0x109a2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe681572db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980bd30>, '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 0x109732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109800be8>, '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.NM3AyOhCc4/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.Q1fu17si7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 10:53:55 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 10:53:55 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 10:53:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NM3AyOhCc4/Render', 10:53:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:55 INFO - 'HOME': '/Users/cltbld', 10:53:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:55 INFO - 'LOGNAME': 'cltbld', 10:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:55 INFO - 'MOZ_NO_REMOTE': '1', 10:53:55 INFO - 'NO_EM_RESTART': '1', 10:53:55 INFO - 'PAGER': '/bin/cat', 10:53:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:55 INFO - 'PWD': '/builds/slave/test', 10:53:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:55 INFO - 'SHELL': '/bin/bash', 10:53:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q1fu17si7P/Listeners', 10:53:55 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 10:53:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:55 INFO - 'USER': 'cltbld', 10:53:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:55 INFO - 'XPC_FLAGS': '0x0', 10:53:55 INFO - 'XPC_SERVICE_NAME': '0', 10:53:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:56 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)) 10:53:56 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)) 10:53:56 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)) 10:53:56 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)) 10:53:56 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)) 10:53:56 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)) 10:53:56 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)) 10:53:56 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)) 10:53:56 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)) 10:53:56 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)) 10:53:56 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)) 10:53:56 INFO - Cleaning up... 10:53:56 INFO - Return code: 0 10:53:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:53:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:53:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109a2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe681572db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10980bd30>, '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 0x109732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109800be8>, '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.NM3AyOhCc4/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.Q1fu17si7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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 10:53:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:53:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:53:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NM3AyOhCc4/Render', 10:53:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:56 INFO - 'HOME': '/Users/cltbld', 10:53:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:56 INFO - 'LOGNAME': 'cltbld', 10:53:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:56 INFO - 'MOZ_NO_REMOTE': '1', 10:53:56 INFO - 'NO_EM_RESTART': '1', 10:53:56 INFO - 'PAGER': '/bin/cat', 10:53:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:56 INFO - 'PWD': '/builds/slave/test', 10:53:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:56 INFO - 'SHELL': '/bin/bash', 10:53:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q1fu17si7P/Listeners', 10:53:56 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 10:53:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:56 INFO - 'USER': 'cltbld', 10:53:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:56 INFO - 'XPC_FLAGS': '0x0', 10:53:56 INFO - 'XPC_SERVICE_NAME': '0', 10:53:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:53:56 INFO - Cleaning up... 10:53:56 INFO - Return code: 0 10:53:56 INFO - Running post-action listener: _resource_record_post_action 10:53:56 INFO - Running post-action listener: _start_resource_monitoring 10:53:56 INFO - Starting resource monitoring. 10:53:56 INFO - [mozharness: 2017-05-25 17:53:56.405856Z] Finished create-virtualenv step (success) 10:53:56 INFO - [mozharness: 2017-05-25 17:53:56.406823Z] Running install step. 10:53:56 INFO - Running pre-action listener: _resource_record_pre_action 10:53:56 INFO - Running main action method: install 10:53:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:53:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:53:56 INFO - Reading from file tmpfile_stdout 10:53:56 INFO - Detecting whether we're running mozinstall >=1.0... 10:53:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:53:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:53:56 INFO - Reading from file tmpfile_stdout 10:53:56 INFO - Output received: 10:53:56 INFO - Usage: mozinstall [options] installer 10:53:56 INFO - Options: 10:53:56 INFO - -h, --help show this help message and exit 10:53:56 INFO - -d DEST, --destination=DEST 10:53:56 INFO - Directory to install application into. [default: 10:53:56 INFO - "/builds/slave/test"] 10:53:56 INFO - --app=APP Application being installed. [default: firefox] 10:53:56 INFO - mkdir: /builds/slave/test/build/application 10:53:56 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'] 10:53:56 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 10:54:11 INFO - Reading from file tmpfile_stdout 10:54:11 INFO - Output received: 10:54:11 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 10:54:11 INFO - Running post-action listener: _resource_record_post_action 10:54:11 INFO - [mozharness: 2017-05-25 17:54:11.548771Z] Finished install step (success) 10:54:11 INFO - [mozharness: 2017-05-25 17:54:11.548891Z] Running run-tests step. 10:54:11 INFO - Running pre-action listener: _resource_record_pre_action 10:54:11 INFO - Running main action method: run_tests 10:54:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:54:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:54:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:54:11 INFO - 2017-05-25 10:54:11.562 screenresolution[1624:7458] starting screenresolution argv=screenresolution get 10:54:11 INFO - 2017-05-25 10:54:11.565 screenresolution[1624:7458] Display 0: 1600x1200x32@50 10:54:11 INFO - 2017-05-25 10:54:11.571 screenresolution[1625:7460] starting screenresolution argv=screenresolution list 10:54:11 INFO - Available Modes on Display 0 10:54:11 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 10:54:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:54:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:54:11 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 10:54:11 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 10:54:11 INFO - Graphics/Displays: 10:54:11 INFO - Intel Iris: 10:54:11 INFO - Chipset Model: Intel Iris 10:54:11 INFO - Type: GPU 10:54:11 INFO - Bus: Built-In 10:54:11 INFO - VRAM (Dynamic, Max): 1536 MB 10:54:11 INFO - Vendor: Intel (0x8086) 10:54:11 INFO - Device ID: 0x0a2e 10:54:11 INFO - Revision ID: 0x0009 10:54:11 INFO - Displays: 10:54:11 INFO - Display: 10:54:11 INFO - Resolution: 1600 x 1200 @ 50 Hz 10:54:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:54:11 INFO - Display Serial Number: 1600x1200 50 10:54:11 INFO - Main Display: Yes 10:54:11 INFO - Mirror: Off 10:54:11 INFO - Online: Yes 10:54:11 INFO - Rotation: Supported 10:54:11 INFO - Return code: 0 10:54:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:54:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:54:11 INFO - Python 2.7.3 10:54:11 INFO - Return code: 0 10:54:11 INFO - grabbing minidump binary from tooltool 10:54:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:11 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 0x10980bd30>, '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 0x109732db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109800be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:54:11 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 10:54:11 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 10:54:11 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 10:54:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:54:11 INFO - Return code: 0 10:54:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:54:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:54:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:54:11 INFO - ENV: RUST_BACKTRACE is now 1 10:54:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:54:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:54:11 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/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0017', '--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 10:54:11 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/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0017 --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 10:54:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NM3AyOhCc4/Render', 10:54:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:11 INFO - 'HOME': '/Users/cltbld', 10:54:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:11 INFO - 'LOGNAME': 'cltbld', 10:54:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:54:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:54:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:11 INFO - 'MOZ_NO_REMOTE': '1', 10:54:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:54:11 INFO - 'NO_EM_RESTART': '1', 10:54:11 INFO - 'PAGER': '/bin/cat', 10:54:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:11 INFO - 'PWD': '/builds/slave/test', 10:54:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:54:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:11 INFO - 'RUST_BACKTRACE': '1', 10:54:11 INFO - 'SHELL': '/bin/bash', 10:54:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q1fu17si7P/Listeners', 10:54:11 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 10:54:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:11 INFO - 'USER': 'cltbld', 10:54:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:11 INFO - 'XPC_FLAGS': '0x0', 10:54:11 INFO - 'XPC_SERVICE_NAME': '0', 10:54:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:11 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/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0017', '--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 10:54:12 INFO - mozversion application_buildid: 20170525093444 10:54:12 INFO - mozversion application_changeset: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 10:54:12 INFO - mozversion application_display_name: Firefox 10:54:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:54:12 INFO - mozversion application_name: Firefox 10:54:12 INFO - mozversion application_remotingname: firefox 10:54:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:54:12 INFO - mozversion application_vendor: Mozilla 10:54:12 INFO - mozversion application_version: 54.0 10:54:12 INFO - mozversion platform_buildid: 20170525093444 10:54:12 INFO - mozversion platform_changeset: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 10:54:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:54:12 INFO - mozversion platform_version: 54.0 10:54:12 INFO - using testdate: 1495734852 10:54:12 INFO - actual date: 1495734852 10:54:12 INFO - starting webserver on 'localhost:49218' 10:54:12 INFO - SUITE-START | Running 2 tests 10:54:12 INFO - TEST-START | dromaeo_css 10:54:12 INFO - operating with platform_type : mac_ 10:54:12 INFO - Initialising browser for dromaeo_css test... 10:54:12 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpd9I6zp/profile http://localhost:49218/getInfo.html) 10:54:14 INFO - PROCESS | 1635 | 2017-05-25 10:54:14.838 plugin-container[1637:7614] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 10:54:14 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:54:14 INFO - PROCESS | 1635 | 2017-05-25 10:54:14.839 plugin-container[1637:7614] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 10:54:14 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:54:14 INFO - PROCESS | 1635 | 2017-05-25 10:54:14.839 plugin-container[1637:7614] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:54:14 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 10:54:14 INFO - PROCESS | 1635 | colorDepth:24 10:54:14 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 10:54:14 INFO - PROCESS | 1635 | __metrics 10:54:15 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 10:54:15 INFO - TEST-INFO | 1635: exit 0 10:54:15 INFO - Browser initialized. 10:54:15 INFO - Running cycle 1/1 for dromaeo_css test... 10:54:15 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpd9I6zp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:54:17 INFO - PROCESS | 1638 | 2017-05-25 10:54:17.720 plugin-container[1639:7747] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 10:54:17 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:54:17 INFO - PROCESS | 1638 | 2017-05-25 10:54:17.720 plugin-container[1639:7747] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 10:54:17 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:54:17 INFO - PROCESS | 1638 | 2017-05-25 10:54:17.720 plugin-container[1639:7747] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:54:27 INFO - PROCESS | 1638 | RSS: Main: 174559232 10:54:27 INFO - PROCESS | 1638 | 10:57:31 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49218/tests/dromaeo/cssquery-ext.html) 10:57:33 INFO - PROCESS | 1638 | RSS: Main: 189714432 10:57:33 INFO - PROCESS | 1638 | 11:00:35 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-ext.html (next: http://localhost:49218/tests/dromaeo/cssquery-jquery.html) 11:00:36 INFO - PROCESS | 1638 | RSS: Main: 203177984 11:00:36 INFO - PROCESS | 1638 | 11:03:38 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49218/tests/dromaeo/cssquery-mootools.html) 11:03:38 INFO - PROCESS | 1638 | RSS: Main: 204824576 11:03:38 INFO - PROCESS | 1638 | 11:06:40 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49218/tests/dromaeo/cssquery-prototype.html) 11:06:41 INFO - PROCESS | 1638 | RSS: Main: 208404480 11:06:41 INFO - PROCESS | 1638 | 11:09:43 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49218/tests/dromaeo/cssquery-yui.html) 11:09:44 INFO - PROCESS | 1638 | RSS: Main: 210919424 11:09:44 INFO - PROCESS | 1638 | 11:12:47 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-yui.html (next: http://localhost:49218/tests/dromaeo/cssquery-dojo.html) 11:12:47 INFO - PROCESS | 1638 | RSS: Main: 209018880 11:12:47 INFO - PROCESS | 1638 | 11:12:47 INFO - PROCESS | 1638 | __start_tp_report 11:12:47 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 11:12:47 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 11:12:47 INFO - PROCESS | 1638 | |i|pagename|runs| 11:12:47 INFO - PROCESS | 1638 | |0;dojo.html;3345.933081338373;3396.9129878438903;3672.155404257021;3695.4826324314586;3722.1997270586853;5328.5470734987575;6220.191375121233;6265.060240963852;6265.3421390754;6286.591371560845;6198.876022479548;6238.78164264252;6249.437550620443;6260.2487701475775;6279;4704.082703872747;5287.920681189766;5649.322081350223;5667.744951477172;5749.166370876221;5726.914096288559;5733.856653583652;5742.885142297151;5748.396418376073;5777.624454410471;5461.262729531502;5475.397706252309;5514.5864060195445;5702.431831246399;5711.657300561958;5642.322921249435;5659.773609055633;5717.171010203539;5746.942530574683;5789.594728369016;5552.167174923753;5655.321361436613;5689.971550142222;5696.316442026942;5722.971385143048;6002.339742628307;6279.780207692755;6300.275468321156;6314.0844577536;6334.334894836068;6432.035194720783;6439.710213040377;6461.709223084926;6566.70449829754;6583.769568065095;6772.051912732221;6788.2193547742145;6801.591904485697;6814.863702725967;6824.146981627297;6271.560990730696;6299.842503937438;6326.905096423558;6354.650494222769;6356.078368636522;6533.085368048477;6584.407403333723;6633.2703402625675;6890.552114112566;6950.54821436605;6470.547061705635;6497.740090396425;6541.47668186544;6555.115059467006;6561.212654481493;5673.205751194836;6570.915798893129;6754.527183097136;6783.186017677812;6814.727410903508;6357.11000459936;6677.399034086956;6691.26396096429;6705.731770729116;6717.328267173243;6337.588056776295;6449.193850768654;6565.212174539086;6595.241547222035;6724.361185687352;4476.6194873436125;5282.864184200338;5396.676199428047;5443.237946687467;5447.809326673548;3965.980170099131;4772.188727916263;4775.2598347256235;4779.211430114062;4782.139214941274;5276.34045744282;5637.928793529216;6328.493720502349;6344.333844946306;6349.206349206349;5764.798232061858;6522.445592124723;6553.803385898431;6608.8347791305605;6691.966540167366;6352.7141278642575;6354.650494222862;6357.713902874381;6364.268109167505;6375.139356186855;6021.909671354933;6030.33666296707;6031.969840150771;6048.485878700271;6051.183090282843;6548.639824809681;6599.769008084886;6856.348646878539;6900.689468974008;7033.085698859218;6748.493862960396;6775.220849602359;6781.796546103625;6797.6941037654415;6815.318468153145;6926.203486598904;6978.371946524634;7001.334873187039;7004.894926576004;7030.9296907030275;6369.20384951881;6418.261899881387;6565.835854103686;6710.094137291599;6715.127033485615;6691.1636045494315;6745.12313399255;6747.6626168692355;6765.391114799691;6770.390664840188;6307.558470906993;6565.638889860912;6570.244421891543;6570.8357291068105;6575.112359831362;6235.812925612239;6309.684515774101;6510.804675859732;6597.241317248578;6606.735730570724;5882.264716910387;6224.470919971853;6457.61254324742;6474.838129046623;6491.935080649133;6545.14913061299;6549.443297319781;6575.835604109744;6597.967010165111;6602;6230.221222347207;6262.997920332725;6308.369163083655;6328.6519241442165;6375.139356186855 11:12:47 INFO - PROCESS | 1638 | |1;ext.html;781.96872125115;823.6664151018837;843.9578021098945;994.4480813148699;997.5461165169849;31960.36079278416;41557.58442415575;43055.13889722204;43176.352354714625;43313.35029974553;41181.176376472526;41487.37768933469;41492;41534.58465415345;41779.79110104445;8384.993800743903;8522.318214542853;11330.263532870378;11626.953574178322;11793.233439826385;11485.966263036325;11527.769444611104;11528.250663706833;11595.710107247301;11603.593874214404;11518.481668324903;11535.942320288386;11572.6528204154;11575.479103440366;11594.42027898606;11532;11535.826962595567;11540.307581545134;11549.884501154966;11560.190786644938;11276;11333.886661133365;11335.546578136864;11342.603008894732;11345.829812552818;35523.11192220189;35777.46333804995;35903.82048089769;35989.64010359915;36111.458328125096;3894.3963685628773;3909.2181563687377;3923.705722070856;3974.1654252606986;4007.3788562772816;21573.029213685266;23600.93795779194;25743.584102874334;26357.20928372152;27880.442391152064;3000.069978306732;3229.9677003229904;3277.524758909945;3309.652486488932;3418.2138108234994;345041;349159;363149;365760;405793;44079;44398.55601444009;44595;44644.99999999935;44654.776726116164;35443;38995.6100438992;44847.327290090674;46131.76934115308;46167;13701.520446784314;13748.862511374959;13839.308034598229;13841.930790345983;13842.723145537033;11930.284182949052;11970.40147992597;12125.09061820367;12187.329696866758;12225.388730563436;11950.88049119515;12026.939865300792;12044.277343359427;12079.818802717966;12141.635750927486;4757.500462451393;5680.318361796611;5725.484706376363;5740.1102829061565;5741.65550066989;1126.318577260762;1146.1289420040662;1147.839302497651;1164.1967042740482;1201.387292480841;22937.88531057334;26636.733632663425;26671.19986400411;26891;26957.326066848487;1108.51225460797;1256.2211428914131;1275.8150068240238;1277.4570807406815;1321.378951892609;13821.963352748384;13857.930710346383;14143.858561414254;14162;14162.221077840817;372648;372775;373538;374099;374561;39026.80486597605;48934.51065489371;56199;57571.71214143903;58115.70942145262;1481.7332880081474;1489.3967942982893;1525.2754941402745;1529.3347393883696;1533.340663514677;25249.621255680813;26574.369577920093;26848;26880;26966.191014269603;44060.457883973904;44149.77925110354;44179.77910110429;44214.77892610645;44339;369345;376329;377026;377746;380652;28186.872525098766;28701.71298287074;32466.513002305455;32508;33705.325893482484;46279;46513.30230046484;46596.835079123295;47537.049259015315;47978.80052998703;26096;32162.356752864343;32204.67795322017;32229.51655725213;32332.83833580817;32227.516587250742;32456.675433245364;32613.673863262014;34984.650153499155;35533.466997995565;39828.20343593054;42079.57920420879;42430.57569424266;42499.787501062296;42847.57152428436;40716.796416018915;41347.793261033505;41686.791566041975;42335.729928102206;42348.15303693971;27758.5836212461;31058.185981172956;32977.83511082429;33057.83471082629;33203.33593328168 11:12:47 INFO - PROCESS | 1638 | |2;jquery.html;418.4372019633592;430.6705370391651;436.556894751827;444.39784092554606;449.341714388421;18574;20154.294599688994;21173.788262117392;21616.783832161673;21869.562608747834;21506.247281345157;21880.89059554704;22209.777902220972;22211.88940552974;22427.77572224281;6873.896891546631;6888.863337549298;7114;7132.928670713279;7133.964330178368;6825.55633883796;6990.860182796341;7049.647517624098;7055.118110236221;7079.646017699094;6778.254392016875;6980.476464265175;7038.1554213494455;7045.11936007999;7055;6815.625140617264;7021.262767409425;7098.787036388891;7104.396126329269;7123.821904452404;6867.828304292432;6871;6884.139482564679;6895.413889819371;6896.793096207071;26682.33294167642;27159;27368.178954631392;27669.169924902286;28079;2967.4806908790874;3058.189579761343;3058.816471011746;3059.571659967606;3087.675794041616;16622.66754664912;17045.318187272493;17096.658066838718;17261.827381726274;17431.47705568835;2206.4042708468623;2225.1766846266823;2275.795178433949;2276.590213761522;2291.117919600949;120472.39763801213;153675.2316238423;156560;158201;159203;21948.561028779335;22810.8859455705;22902.541949161256;22906.198283060014;23103.306900793003;32309.67690323114;32367.190820229684;32991.83504082512;33143.3371332572;33153.83423082869;9566.473843938651;10778.622748203932;10784.78430431387;11123.666290011313;11263.098952083814;9162.908370916339;9201.861972070425;9240.5841737122;9310.53447327631;9346.859797103049;9961.950190249003;10084.949575252223;10088.394696318097;10211.489425528694;10219.591216351262;3610.693091087287;3695.6119607440833;4191.371294305878;4243.533211346749;4316.7409955402145;842.4902933725131;860.414917855864;993.4039576254335;1023.6519583341551;1033.8914413986574;20293;21334.768139335705;21336;21380.78619213788;21522.031508582153;818.414833394126;978.8972157923458;996.8106059848606;1006.5872992073215;1016.4866742295242;10810.297330673638;11093;11106.333619982826;11114.833277500844;11306.925842044993;765820;819908;825848;833575;835219;118392;150865;162346.37653623312;163100;163645.18177409275;1189.5895915908998;1275.859655437901;1299.3243513372993;1322.8412590489204;1382.032577195967;17553.473395798148;17557.648847022734;18200.635987280446;19095;19411.73823701455;47710.284345734144;61950.69024654848;62377.12868613949;62434.37565624285;62497.68751156215;67054;75440.24559754552;86887.56556217179;88156;88858.55570722105;7390.556566606021;10000.499975001076;11333;11640.35978021217;12082.577109801116;12912.935435322763;13057.869421305666;13086.14940028895;13368.197908125543;13425.194488330731;11225.887741122811;11260.774784504429;11376.2366833141;11520.251183673034;11652.94173529127;10056.44689542053;10500.842487362848;10563.613779315081;10730.248882578457;10835.187360947803;11478.540858365906;11576.942115289707;11647.068234541386;11821.862348178413;11843.28940263587;11193.440327983406;11688.70778230551;11874.168808183344;12002.39988000614;12055.15613907018;11232.606858759904;11310.660680179608;11547;11562.94218528902;11690.76618467609 11:12:47 INFO - PROCESS | 1638 | |3;mootools.html;1636.8608668263198;1650.8349165083494;1665.2589597629055;1672.3477843640983;1687.9662406751863;11037.227394082418;11470.967612914836;11711.765764684702;11759.7648047039;11886.346250956216;11027.724306892333;11217.887821121787;11233.775324493505;11239.494222759975;11256.099512039038;2518.8362756420775;2564.8845801938955;2567.3709941064467;2567.756063174005;2570.3060173753074;2280.395695140789;2435.366804630791;2490.414752533153;2556.399246177147;2568.2295311406597;2497.7627125423055;2543.109911530968;2544.440223150904;2556.322574517754;2567.0501914291644;2093.3929160543425;2102.190656597206;2375.4061484628755;2412.9034838606435;2413.613821788514;2197.8021978021893;2446.3150317911013;2537.8350407223656;2547.261294224673;2597.948041039169;11542.538298468051;11627;11780.410979451079;11789.941050294694;11800.704982375424;6127.662978536178;6206.534509911775;6212.9068063979075;6226.2839773426185;6227.688615569204;3821.0577855051733;3833.9424908626393;3862.6909847212155;3925.6074392560517;3955.1101002274545;1249.056961993689;1269.0165122030407;1282.7947528395505;1300.1873828856965;1311.7114234868313;14313.069650472686;14618.780718289234;15520.301586428637;16197.91901040499;16307.184640768033;4212;4887.3890763654545;4913.410390753133;5061.1902095665255;5078.933423980931;12175.330356830282;12376.381180940916;12381.690457738629;12383.133180677267;12410.379481025913;6161.507079433634;6161.784337548164;6165.290991535941;6173.444390004831;6179.721912513947;4713.6936099153445;5481.314835645544;5598.664080155204;5599.916001259984;5624.21261023457;4080.227083774017;4142.855000075043;4144.088300573869;4162.7502349859105;4181.310083836193;992.6972273456665;1044.206403133624;1097.0455703537814;1107.379867274329;1110.7556337605713;827.437342607033;963.3882484622176;972.8881178664402;981.2983716642635;1002.9147522460527;6834.8633027340165;6863.725450981905;6883.3116688330765;7055.647217639097;7095.503314767917;1013.5641674080216;1054.7468607534136;1055.5355643516982;1056.529844219315;1082.8429877667852;4381.978090109593;4388.5830846069575;4402.515723270438;4414.337849322563;4511.887202819956;12478;13830.86169138316;14025.719485610434;14418.639534011523;14474.203918784568;13432.731345373038;13946;13946.442142314396;13948.093373930662;14347.56957291283;8845.4250473719;9003.099690031075;10343.27597068213;10610.628627997983;10683.732906677395;5615.859603509945;7158.105236845395;7228;7257.455690823315;7366.300201480851;12348.197367171106;12496.375181241083;12511.311877846805;12960.157589756636;13117.409716562968;10723.30298530593;10729.463526823783;13118.212907225598;13265.270410127536;13310.866891330963;8647.56762161902;9080.091990800867;9491.762705932408;9552.90447095548;9718.805623887623;10605.787884242427;10655.57377704883;10684.46577671129;10771.246012779344;10911.672649820519;9163.358564900393;9272.953635231781;9282.071792820663;9304.767380815534;9401;9028.774280643036;9047.411918225294;9062.365634405793;9095.181433671001;9126.77183070402;8317.875231871647;8360.12218717026;8479.872801908099;8486.83026339482;8500.404971651926;6039.516838652985;6064.484518815773;6071.666058366833;6170.7223174958135;6250.1874756281395;6110.511159107172;6212.285587157535;6239.282482514569;6315.905261421174;6318.968405157945 11:12:47 INFO - PROCESS | 1638 | |4;prototype.html;395.2845349916653;400.80759739774174;404.61561516559266;404.78950945508325;405.8721502726641;4514.268867335264;5443.210734443508;5540.612157149001;5549.694766787825;5558.110242517549;4340.826366945318;4965.226427914784;5278.287431196787;5290.6140070355;5354.866128346794;4394.6923715339935;4396.098799746044;4413.514513403531;4414.381986521874;4422.867313980586;3929.6659783918403;3971.6028397160344;4471.597556219939;4490.41624588803;4509.7970591323465;3727.384981478053;3757.567879693829;4245.34197199433;4448.755318457484;4474.574915383034;3750.412215571247;4128.4632997710105;4338.934915976247;4390.934135987963;4439.88900277494;3575.821208939569;3878.2437424702193;3917.5494818096;3953.9802300988313;4093.5175982299634;4700.038975445482;4832.9758351208375;5219.781592823073;5227.477252274742;5238.266642670029;4617.37665415168;4950;5010.398752149729;5051.848444546669;5194.532492075694;5113.437521872591;5292.708901010443;5693.544516438675;5719.197593179312;5811.506021988136;3936.5079365079364;4236.364545318227;4466.285394336891;4595.908081838378;4602.930956035662;4582.03777206787;5049.364457590529;5945.640712997143;5997.490213331872;6024.789132380346;4076.8584796257096;4513.616342610849;4723.385959825201;4735.526447355237;4758;4314.56854314566;4615.3999980003055;4766.594839438617;4815.858361808841;4866.926996095061;4540.480033581328;4867.61059115277;5124.487551244845;5156.988774377921;5182.844514664566;4643.281985665325;5053.46938571452;5377.784888604412;5381.650192737459;5697.205380305273;1434.2470203143225;1553.9766903496457;1569.6546759712844;1612.057470747746;1623.8944468609502;1066.8879767624444;1067.588978243382;1124.9156313276537;1200.6218041316956;1231.901447884167;4755.215389460769;4864.805407783649;4949.48030456804;5026.698398096126;5448.482394172547;5951;5960.344362120163;5970.880582388328;5993.760249589967;6005.036675793955;4387.758673273001;4618.792154353062;5253.4221235664045;5313.847891603907;5436.70098144598;2721.8840275486955;2753.6695596528543;2788.0381268462347;3097.2102113960796;3143.281061881502;5333.359996800409;5903.2325797646035;5933.970330148321;6076.939230607726;6156.7070915108325;5456.781728730806;5621.437856214346;5762.86773893662;6061.514928842462;6116.440307721503;5307.99148161848;5987.520998320037;6004.579679422399;6150.292716337589;6153.292371377349;4957.851264462072;5924.407559244041;5929.644221346733;5935.970320148372;5994.820155395345;5297.496737809902;5612.494875461228;5697.4587414195585;5877.441643043904;5879.676617786063;5173.793048278027;5210.843674689765;5885.529157667462;5987.5330544017;6106.603070800384;3941.408788681606;4312.878651550556;4315.532718875597;4612.354270402079;4613.284940834176;4904.681195722266;4935.8025678972435;5144.742762861767;5518.91721624184;5632.802851900164;5154.407243167084;5255.214736189825;5579;5781.306243250669;5801.274840644919;4819.49395313487;5506.614536982533;5702.401247868914;5814.604494921273;5934.495567876779;5058.735316170957;5306.204069389622;5340.973295133499;5544.532682956045;5697.54419646419;4980.452150263396;5175.835437026639;5692;5770;5835.586182083529;4804.615630749462;4967.776450059829;5454.3454785426;5699.458551437607;5705.258316418839 11:12:47 INFO - PROCESS | 1638 | |5;yui.html;749.9325060744532;763.4236151705461;818.1041759273593;845.7885528617845;883.5758835758836;16408.26162822673;17437.040962747044;17474.126293685313;17513.299468021265;17543.912280438595;13499.39252733627;15880.126593037377;16008.83991160088;16061.83938160618;16065.518034458984;946.8059047895182;965.1410244882061;969.2149359019181;969.8108868770593;976.623999760094;999.7850462150636;1000.104906109032;1008.7730260691359;1011.6964910526849;1012.0132870451324;1060.9204309676766;1062.2511129653615;1062.3891262524041;1064.0264158295151;1064.4571268653003;1035.1149766949263;1052.610534102387;1052.973675658107;1053.8261186904151;1054.8206804843157;743.2344684974485;770.245159743449;782.4561929459028;785.5797148525511;786.5831109511968;15964.840351596451;17298.048607326593;17626.91186544059;17968.460946171635;18305.7254141188;14682.55952321432;14813.037152585048;14847.331870065873;15052.924735376362;15199.392024319013;12074.922350571604;12147.149699521036;12338.938305308506;12340.568080117153;12954.935225323907;3849.653531182207;3889.183271512986;3947;3980.6218409251073;4089.672826173899;114910.27634585487;115523;118711.8128818718;122175;123933;11103.5558577656;11237.157213209042;11477.942610286895;11732.12009099304;12213.75572488563;24057.75942240555;24404.145854894992;24404.389890252885;24431.14490992807;24503.38741531476;8295.505561929418;8310.459820111673;8413.537255451009;8422.452540584842;8467.61895714682;4573.176828170895;4604.539546045368;4675.122806667926;4695.835645752382;4847.878803029953;3683.4290684943876;3744.6817020553012;3764.4917936078277;3795.6014618465215;3797.468354430382;655.2890114226022;706.2866504830056;736.1203361982487;746.0748671647118;749.6813854111981;632.7595513704855;713.6360456167392;723.9131304243524;736.2747693521877;743.4349894080538;9563.713088607354;11013.83479247812;11047.558097676003;11085.72285692848;11261.943690281496;696.9756058538028;719.7768691705588;731.1737736357882;732.1653315220652;752.8419032003231;5148.489300035753;5212.296339994128;5223.955208958224;5224.320838290998;5288.101022826128;74088.88866667004;99836;107763.46118269359;108189;110231;94772;95769.5634565482;95898.52050739701;97282.5135874316;97949.51025244828;80996.59501702453;81298.59350703208;83489.58255208685;84150.37172955424;84762.30475390458;10158.949205253926;10319.432431216055;10321.535530901278;10346.689599312032;10380;47936.7603161982;48865;50300.49699503105;52805.47194528006;54227.45772542224;67654.3234567648;68505;72181.91727123992;72224.63887680529;72874;13289.80065299002;13332.666683332995;13484;13777.862221377658;13984.091034082752;15224.391024358902;15370.846291536942;15543.766843497582;15665.216739163225;16285.941413808065;14110;14412.711745764816;14680;14705.33825977855;15282.312295946487;14030.298485075502;14596.197209153173;14711.779323309946;14730.410783568537;15026.323815428113;14172.929135354258;14263.944524692188;14297.35661895238;14390.352434140279;14393.424263029361;9005.369624126248;9897.703068907944;10264.794704106025;10353;10545;8982.579330248856;9854;11599.420028998686;11601.477933493183;11758.764824703629 11:12:47 INFO - PROCESS | 1638 | __end_tp_report 11:12:47 INFO - PROCESS | 1638 | __start_cc_report 11:12:47 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,403 11:12:47 INFO - PROCESS | 1638 | __end_cc_report 11:12:47 INFO - PROCESS | 1638 | __startTimestamp1495735967714__endTimestamp 11:12:47 INFO - PROCESS | 1638 | 11:12:47 INFO - PROCESS | 1638 | ------- Summary: start ------- 11:12:47 INFO - PROCESS | 1638 | Number of tests: 6 11:12:47 INFO - PROCESS | 1638 | 11:12:47 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:6164.56 Median:6354.65 stddev:718.05 (11.3%) stddev-sans-first:685.36 11:12:47 INFO - PROCESS | 1638 | Values: 3345.9 3396.9 3672.2 3695.5 3722.2 5328.5 6220.2 6265.1 6265.3 6286.6 6198.9 6238.8 6249.4 6260.2 6279.0 4704.1 5287.9 5649.3 5667.7 5749.2 5726.9 5733.9 5742.9 5748.4 5777.6 5461.3 5475.4 5514.6 5702.4 5711.7 5642.3 5659.8 5717.2 5746.9 5789.6 5552.2 5655.3 5690.0 5696.3 5723.0 6002.3 6279.8 6300.3 6314.1 6334.3 6432.0 6439.7 6461.7 6566.7 6583.8 6772.1 6788.2 6801.6 6814.9 6824.1 6271.6 6299.8 6326.9 6354.7 6356.1 6533.1 6584.4 6633.3 6890.6 6950.5 6470.5 6497.7 6541.5 6555.1 6561.2 5673.2 6570.9 6754.5 6783.2 6814.7 6357.1 6677.4 6691.3 6705.7 6717.3 6337.6 6449.2 6565.2 6595.2 6724.4 4476.6 5282.9 5396.7 5443.2 5447.8 3966.0 4772.2 4775.3 4779.2 4782.1 5276.3 5637.9 6328.5 6344.3 6349.2 5764.8 6522.4 6553.8 6608.8 6692.0 6352.7 6354.7 6357.7 6364.3 6375.1 6021.9 6030.3 6032.0 6048.5 6051.2 6548.6 6599.8 6856.3 6900.7 7033.1 6748.5 6775.2 6781.8 6797.7 6815.3 6926.2 6978.4 7001.3 7004.9 7030.9 6369.2 6418.3 6565.8 6710.1 6715.1 6691.2 6745.1 6747.7 6765.4 6770.4 6307.6 6565.6 6570.2 6570.8 6575.1 6235.8 6309.7 6510.8 6597.2 6606.7 5882.3 6224.5 6457.6 6474.8 6491.9 6545.1 6549.4 6575.8 6598.0 6602.0 6230.2 6263.0 6308.4 6328.7 6375.1 11:12:47 INFO - PROCESS | 1638 | 11:12:47 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:52158.00 Median:18127.68 stddev:97987.96 (540.5%) stddev-sans-first:98186.92 11:12:47 INFO - PROCESS | 1638 | Values: 782.0 823.7 844.0 994.4 997.5 31960.4 41557.6 43055.1 43176.4 43313.4 41181.2 41487.4 41492.0 41534.6 41779.8 8385.0 8522.3 11330.3 11627.0 11793.2 11486.0 11527.8 11528.3 11595.7 11603.6 11518.5 11535.9 11572.7 11575.5 11594.4 11532.0 11535.8 11540.3 11549.9 11560.2 11276.0 11333.9 11335.5 11342.6 11345.8 35523.1 35777.5 35903.8 35989.6 36111.5 3894.4 3909.2 3923.7 3974.2 4007.4 21573.0 23600.9 25743.6 26357.2 27880.4 3000.1 3230.0 3277.5 3309.7 3418.2 345041.0 349159.0 363149.0 365760.0 405793.0 44079.0 44398.6 44595.0 44645.0 44654.8 35443.0 38995.6 44847.3 46131.8 46167.0 13701.5 13748.9 13839.3 13841.9 13842.7 11930.3 11970.4 12125.1 12187.3 12225.4 11950.9 12026.9 12044.3 12079.8 12141.6 4757.5 5680.3 5725.5 5740.1 5741.7 1126.3 1146.1 1147.8 1164.2 1201.4 22937.9 26636.7 26671.2 26891.0 26957.3 1108.5 1256.2 1275.8 1277.5 1321.4 13822.0 13857.9 14143.9 14162.0 14162.2 372648.0 372775.0 373538.0 374099.0 374561.0 39026.8 48934.5 56199.0 57571.7 58115.7 1481.7 1489.4 1525.3 1529.3 1533.3 25249.6 26574.4 26848.0 26880.0 26966.2 44060.5 44149.8 44179.8 44214.8 44339.0 369345.0 376329.0 377026.0 377746.0 380652.0 28186.9 28701.7 32466.5 32508.0 33705.3 46279.0 46513.3 46596.8 47537.0 47978.8 26096.0 32162.4 32204.7 32229.5 32332.8 32227.5 32456.7 32613.7 34984.7 35533.5 39828.2 42079.6 42430.6 42499.8 42847.6 40716.8 41347.8 41686.8 42335.7 42348.2 27758.6 31058.2 32977.8 33057.8 33203.3 11:12:47 INFO - PROCESS | 1638 | 11:12:47 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:44718.27 Median:22210.83 stddev:135445.84 (609.8%) stddev-sans-first:135784.95 11:12:47 INFO - PROCESS | 1638 | Values: 418.4 430.7 436.6 444.4 449.3 18574.0 20154.3 21173.8 21616.8 21869.6 21506.2 21880.9 22209.8 22211.9 22427.8 6873.9 6888.9 7114.0 7132.9 7134.0 6825.6 6990.9 7049.6 7055.1 7079.6 6778.3 6980.5 7038.2 7045.1 7055.0 6815.6 7021.3 7098.8 7104.4 7123.8 6867.8 6871.0 6884.1 6895.4 6896.8 26682.3 27159.0 27368.2 27669.2 28079.0 2967.5 3058.2 3058.8 3059.6 3087.7 16622.7 17045.3 17096.7 17261.8 17431.5 2206.4 2225.2 2275.8 2276.6 2291.1 120472.4 153675.2 156560.0 158201.0 159203.0 21948.6 22810.9 22902.5 22906.2 23103.3 32309.7 32367.2 32991.8 33143.3 33153.8 9566.5 10778.6 10784.8 11123.7 11263.1 9162.9 9201.9 9240.6 9310.5 9346.9 9962.0 10084.9 10088.4 10211.5 10219.6 3610.7 3695.6 4191.4 4243.5 4316.7 842.5 860.4 993.4 1023.7 1033.9 20293.0 21334.8 21336.0 21380.8 21522.0 818.4 978.9 996.8 1006.6 1016.5 10810.3 11093.0 11106.3 11114.8 11306.9 765820.0 819908.0 825848.0 833575.0 835219.0 118392.0 150865.0 162346.4 163100.0 163645.2 1189.6 1275.9 1299.3 1322.8 1382.0 17553.5 17557.6 18200.6 19095.0 19411.7 47710.3 61950.7 62377.1 62434.4 62497.7 67054.0 75440.2 86887.6 88156.0 88858.6 7390.6 10000.5 11333.0 11640.4 12082.6 12912.9 13057.9 13086.1 13368.2 13425.2 11225.9 11260.8 11376.2 11520.3 11652.9 10056.4 10500.8 10563.6 10730.2 10835.2 11478.5 11576.9 11647.1 11821.9 11843.3 11193.4 11688.7 11874.2 12002.4 12055.2 11232.6 11310.7 11547.0 11562.9 11690.8 11:12:47 INFO - PROCESS | 1638 | 11:12:47 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6978.04 Median:2560.64 stddev:4305.49 (168.1%) stddev-sans-first:4298.87 11:12:47 INFO - PROCESS | 1638 | Values: 1636.9 1650.8 1665.3 1672.3 1688.0 11037.2 11471.0 11711.8 11759.8 11886.3 11027.7 11217.9 11233.8 11239.5 11256.1 2518.8 2564.9 2567.4 2567.8 2570.3 2280.4 2435.4 2490.4 2556.4 2568.2 2497.8 2543.1 2544.4 2556.3 2567.1 2093.4 2102.2 2375.4 2412.9 2413.6 2197.8 2446.3 2537.8 2547.3 2597.9 11542.5 11627.0 11780.4 11789.9 11800.7 6127.7 6206.5 6212.9 6226.3 6227.7 3821.1 3833.9 3862.7 3925.6 3955.1 1249.1 1269.0 1282.8 1300.2 1311.7 14313.1 14618.8 15520.3 16197.9 16307.2 4212.0 4887.4 4913.4 5061.2 5078.9 12175.3 12376.4 12381.7 12383.1 12410.4 6161.5 6161.8 6165.3 6173.4 6179.7 4713.7 5481.3 5598.7 5599.9 5624.2 4080.2 4142.9 4144.1 4162.8 4181.3 992.7 1044.2 1097.0 1107.4 1110.8 827.4 963.4 972.9 981.3 1002.9 6834.9 6863.7 6883.3 7055.6 7095.5 1013.6 1054.7 1055.5 1056.5 1082.8 4382.0 4388.6 4402.5 4414.3 4511.9 12478.0 13830.9 14025.7 14418.6 14474.2 13432.7 13946.0 13946.4 13948.1 14347.6 8845.4 9003.1 10343.3 10610.6 10683.7 5615.9 7158.1 7228.0 7257.5 7366.3 12348.2 12496.4 12511.3 12960.2 13117.4 10723.3 10729.5 13118.2 13265.3 13310.9 8647.6 9080.1 9491.8 9552.9 9718.8 10605.8 10655.6 10684.5 10771.2 10911.7 9163.4 9273.0 9282.1 9304.8 9401.0 9028.8 9047.4 9062.4 9095.2 9126.8 8317.9 8360.1 8479.9 8486.8 8500.4 6039.5 6064.5 6071.7 6170.7 6250.2 6110.5 6212.3 6239.3 6315.9 6319.0 11:12:47 INFO - PROCESS | 1638 | 11:12:47 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4713.58 Median:5050.61 stddev:1331.78 (26.4%) stddev-sans-first:1295.47 11:12:47 INFO - PROCESS | 1638 | Values: 395.3 400.8 404.6 404.8 405.9 4514.3 5443.2 5540.6 5549.7 5558.1 4340.8 4965.2 5278.3 5290.6 5354.9 4394.7 4396.1 4413.5 4414.4 4422.9 3929.7 3971.6 4471.6 4490.4 4509.8 3727.4 3757.6 4245.3 4448.8 4474.6 3750.4 4128.5 4338.9 4390.9 4439.9 3575.8 3878.2 3917.5 3954.0 4093.5 4700.0 4833.0 5219.8 5227.5 5238.3 4617.4 4950.0 5010.4 5051.8 5194.5 5113.4 5292.7 5693.5 5719.2 5811.5 3936.5 4236.4 4466.3 4595.9 4602.9 4582.0 5049.4 5945.6 5997.5 6024.8 4076.9 4513.6 4723.4 4735.5 4758.0 4314.6 4615.4 4766.6 4815.9 4866.9 4540.5 4867.6 5124.5 5157.0 5182.8 4643.3 5053.5 5377.8 5381.7 5697.2 1434.2 1554.0 1569.7 1612.1 1623.9 1066.9 1067.6 1124.9 1200.6 1231.9 4755.2 4864.8 4949.5 5026.7 5448.5 5951.0 5960.3 5970.9 5993.8 6005.0 4387.8 4618.8 5253.4 5313.8 5436.7 2721.9 2753.7 2788.0 3097.2 3143.3 5333.4 5903.2 5934.0 6076.9 6156.7 5456.8 5621.4 5762.9 6061.5 6116.4 5308.0 5987.5 6004.6 6150.3 6153.3 4957.9 5924.4 5929.6 5936.0 5994.8 5297.5 5612.5 5697.5 5877.4 5879.7 5173.8 5210.8 5885.5 5987.5 6106.6 3941.4 4312.9 4315.5 4612.4 4613.3 4904.7 4935.8 5144.7 5518.9 5632.8 5154.4 5255.2 5579.0 5781.3 5801.3 4819.5 5506.6 5702.4 5814.6 5934.5 5058.7 5306.2 5341.0 5544.5 5697.5 4980.5 5175.8 5692.0 5770.0 5835.6 4804.6 4967.8 5454.3 5699.5 5705.3 11:12:47 INFO - PROCESS | 1638 | 11:12:47 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:21714.32 Median:18137.09 stddev:31157.85 (171.8%) stddev-sans-first:31205.50 11:12:47 INFO - PROCESS | 1638 | Values: 749.9 763.4 818.1 845.8 883.6 16408.3 17437.0 17474.1 17513.3 17543.9 13499.4 15880.1 16008.8 16061.8 16065.5 946.8 965.1 969.2 969.8 976.6 999.8 1000.1 1008.8 1011.7 1012.0 1060.9 1062.3 1062.4 1064.0 1064.5 1035.1 1052.6 1053.0 1053.8 1054.8 743.2 770.2 782.5 785.6 786.6 15964.8 17298.0 17626.9 17968.5 18305.7 14682.6 14813.0 14847.3 15052.9 15199.4 12074.9 12147.1 12338.9 12340.6 12954.9 3849.7 3889.2 3947.0 3980.6 4089.7 114910.3 115523.0 118711.8 122175.0 123933.0 11103.6 11237.2 11477.9 11732.1 12213.8 24057.8 24404.1 24404.4 24431.1 24503.4 8295.5 8310.5 8413.5 8422.5 8467.6 4573.2 4604.5 4675.1 4695.8 4847.9 3683.4 3744.7 3764.5 3795.6 3797.5 655.3 706.3 736.1 746.1 749.7 632.8 713.6 723.9 736.3 743.4 9563.7 11013.8 11047.6 11085.7 11261.9 697.0 719.8 731.2 732.2 752.8 5148.5 5212.3 5224.0 5224.3 5288.1 74088.9 99836.0 107763.5 108189.0 110231.0 94772.0 95769.6 95898.5 97282.5 97949.5 80996.6 81298.6 83489.6 84150.4 84762.3 10158.9 10319.4 10321.5 10346.7 10380.0 47936.8 48865.0 50300.5 52805.5 54227.5 67654.3 68505.0 72181.9 72224.6 72874.0 13289.8 13332.7 13484.0 13777.9 13984.1 15224.4 15370.8 15543.8 15665.2 16285.9 14110.0 14412.7 14680.0 14705.3 15282.3 14030.3 14596.2 14711.8 14730.4 15026.3 14172.9 14263.9 14297.4 14390.4 14393.4 9005.4 9897.7 10264.8 10353.0 10545.0 8982.6 9854.0 11599.4 11601.5 11758.8 11:12:47 INFO - PROCESS | 1638 | -------- Summary: end -------- 11:12:47 INFO - PROCESS | 1638 | 11:12:48 INFO - TEST-INFO | 1638: exit 0 11:12:48 INFO - TEST-OK | dromaeo_css | took 1116167ms 11:12:48 INFO - TEST-START | kraken 11:12:48 INFO - operating with platform_type : mac_ 11:12:48 INFO - Initialising browser for kraken test... 11:12:48 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpbAQp7v/profile http://localhost:49218/getInfo.html) 11:12:50 INFO - PROCESS | 1650 | 2017-05-25 11:12:50.741 plugin-container[1652:10145] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 11:12:50 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:12:50 INFO - PROCESS | 1650 | 2017-05-25 11:12:50.742 plugin-container[1652:10145] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.CFPasteboardClient' 11:12:50 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:12:50 INFO - PROCESS | 1650 | 2017-05-25 11:12:50.742 plugin-container[1652:10145] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:12:50 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 11:12:50 INFO - PROCESS | 1650 | colorDepth:24 11:12:50 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 11:12:50 INFO - PROCESS | 1650 | __metrics 11:12:50 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 11:12:51 INFO - TEST-INFO | 1650: exit 0 11:12:51 INFO - Browser initialized. 11:12:51 INFO - Running cycle 1/1 for kraken test... 11:12:51 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpbAQp7v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:12:53 INFO - PROCESS | 1653 | 2017-05-25 11:12:53.923 plugin-container[1654:10268] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x844b, name = 'com.apple.tsm.portname' 11:12:53 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:12:53 INFO - PROCESS | 1653 | 2017-05-25 11:12:53.924 plugin-container[1654:10268] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b17, name = 'com.apple.CFPasteboardClient' 11:12:53 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:12:53 INFO - PROCESS | 1653 | 2017-05-25 11:12:53.924 plugin-container[1654:10268] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:13:03 INFO - PROCESS | 1653 | RSS: Main: 174256128 11:13:03 INFO - PROCESS | 1653 | 11:13:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-beat-detection) 11:13:07 INFO - PROCESS | 1653 | RSS: Main: 187617280 11:13:07 INFO - PROCESS | 1653 | 11:13:10 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-dft) 11:13:10 INFO - PROCESS | 1653 | RSS: Main: 195596288 11:13:10 INFO - PROCESS | 1653 | 11:13:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-fft) 11:13:14 INFO - PROCESS | 1653 | RSS: Main: 199311360 11:13:14 INFO - PROCESS | 1653 | 11:13:16 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-oscillator) 11:13:17 INFO - PROCESS | 1653 | RSS: Main: 200843264 11:13:17 INFO - PROCESS | 1653 | 11:13:19 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49218/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:13:19 INFO - PROCESS | 1653 | RSS: Main: 200773632 11:13:19 INFO - PROCESS | 1653 | 11:13:22 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49218/tests/kraken/driver.html?testName=imaging-darkroom) 11:13:23 INFO - PROCESS | 1653 | RSS: Main: 204984320 11:13:23 INFO - PROCESS | 1653 | 11:13:26 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49218/tests/kraken/driver.html?testName=imaging-desaturate) 11:13:27 INFO - PROCESS | 1653 | RSS: Main: 205508608 11:13:27 INFO - PROCESS | 1653 | 11:13:30 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49218/tests/kraken/driver.html?testName=json-parse-financial) 11:13:30 INFO - PROCESS | 1653 | RSS: Main: 204820480 11:13:30 INFO - PROCESS | 1653 | 11:13:32 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49218/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:13:33 INFO - PROCESS | 1653 | RSS: Main: 204124160 11:13:33 INFO - PROCESS | 1653 | 11:13:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:13:35 INFO - PROCESS | 1653 | RSS: Main: 204521472 11:13:35 INFO - PROCESS | 1653 | 11:13:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:13:38 INFO - PROCESS | 1653 | RSS: Main: 204521472 11:13:38 INFO - PROCESS | 1653 | 11:13:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:13:42 INFO - PROCESS | 1653 | RSS: Main: 204894208 11:13:42 INFO - PROCESS | 1653 | 11:13:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:13:45 INFO - PROCESS | 1653 | RSS: Main: 204943360 11:13:45 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49218/tests/kraken/driver.html?testName=ai-astar) 11:13:47 INFO - PROCESS | 1653 | RSS: Main: 206856192 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | __start_tp_report 11:13:47 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 11:13:47 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 11:13:47 INFO - PROCESS | 1653 | |i|pagename|runs| 11:13:47 INFO - PROCESS | 1653 | |0;ai-astar;111;122;119;124;178;180;124;118;121;115 11:13:47 INFO - PROCESS | 1653 | |1;audio-beat-detection;141;120;111;145;109;109;108;111;126;111 11:13:47 INFO - PROCESS | 1653 | |2;audio-dft;192;198;199;193;198;194;198;198;196;196 11:13:47 INFO - PROCESS | 1653 | |3;audio-fft;85;58;59;86;60;76;83;83;73;60 11:13:47 INFO - PROCESS | 1653 | |4;audio-oscillator;80;71;84;73;72;68;79;69;70;82 11:13:47 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;76;135;136;89;88;87;93;92;136;137 11:13:47 INFO - PROCESS | 1653 | |6;imaging-darkroom;95;97;96;95;95;101;150;95;96;96 11:13:47 INFO - PROCESS | 1653 | |7;imaging-desaturate;90;95;138;96;142;140;89;93;92;90 11:13:47 INFO - PROCESS | 1653 | |8;json-parse-financial;66;65;64;67;69;68;70;69;69;68 11:13:47 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;62;59;62;99;93;88;57;58;60;60 11:13:47 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;86;98;101;98;102;99;101;76;82;87 11:13:47 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;135;139;151;153;127;141;155;150;189;149 11:13:47 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;163;164;162;164;160;166;164;163;165;161 11:13:47 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;65;64;63;58;81;91;89;68;63;64 11:13:47 INFO - PROCESS | 1653 | __end_tp_report 11:13:47 INFO - PROCESS | 1653 | __start_cc_report 11:13:47 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,997 11:13:47 INFO - PROCESS | 1653 | __end_cc_report 11:13:47 INFO - PROCESS | 1653 | __startTimestamp1495736027654__endTimestamp 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | ------- Summary: start ------- 11:13:47 INFO - PROCESS | 1653 | Number of tests: 14 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:131.20 Median:123.00 stddev:25.51 (20.7%) stddev-sans-first:25.99 11:13:47 INFO - PROCESS | 1653 | Values: 111.0 122.0 119.0 124.0 178.0 180.0 124.0 118.0 121.0 115.0 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:119.10 Median:115.50 stddev:13.84 (12.0%) stddev-sans-first:12.20 11:13:47 INFO - PROCESS | 1653 | Values: 141.0 120.0 111.0 145.0 109.0 109.0 108.0 111.0 126.0 111.0 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:196.20 Median:198.00 stddev:2.44 (1.2%) stddev-sans-first:2.06 11:13:47 INFO - PROCESS | 1653 | Values: 192.0 198.0 199.0 193.0 198.0 194.0 198.0 198.0 196.0 196.0 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:72.30 Median:79.50 stddev:11.91 (15.0%) stddev-sans-first:11.71 11:13:47 INFO - PROCESS | 1653 | Values: 85.0 58.0 59.0 86.0 60.0 76.0 83.0 83.0 73.0 60.0 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:74.80 Median:76.00 stddev:5.87 (7.7%) stddev-sans-first:5.91 11:13:47 INFO - PROCESS | 1653 | Values: 80.0 71.0 84.0 73.0 72.0 68.0 79.0 69.0 70.0 82.0 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:106.90 Median:87.50 stddev:25.46 (29.1%) stddev-sans-first:24.42 11:13:47 INFO - PROCESS | 1653 | Values: 76.0 135.0 136.0 89.0 88.0 87.0 93.0 92.0 136.0 137.0 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:95.50 stddev:17.10 (17.9%) stddev-sans-first:17.97 11:13:47 INFO - PROCESS | 1653 | Values: 95.0 97.0 96.0 95.0 95.0 101.0 150.0 95.0 96.0 96.0 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:106.50 Median:91.00 stddev:23.24 (25.5%) stddev-sans-first:23.87 11:13:47 INFO - PROCESS | 1653 | Values: 90.0 95.0 138.0 96.0 142.0 140.0 89.0 93.0 92.0 90.0 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:67.50 Median:68.50 stddev:1.96 (2.9%) stddev-sans-first:2.00 11:13:47 INFO - PROCESS | 1653 | Values: 66.0 65.0 64.0 67.0 69.0 68.0 70.0 69.0 69.0 68.0 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:62.00 stddev:16.52 (26.6%) stddev-sans-first:17.28 11:13:47 INFO - PROCESS | 1653 | Values: 62.0 59.0 62.0 99.0 93.0 88.0 57.0 58.0 60.0 60.0 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:86.50 stddev:9.37 (10.8%) stddev-sans-first:9.59 11:13:47 INFO - PROCESS | 1653 | Values: 86.0 98.0 101.0 98.0 102.0 99.0 101.0 76.0 82.0 87.0 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:150.50 stddev:16.67 (11.1%) stddev-sans-first:16.90 11:13:47 INFO - PROCESS | 1653 | Values: 135.0 139.0 151.0 153.0 127.0 141.0 155.0 150.0 189.0 149.0 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.20 Median:164.00 stddev:1.81 (1.1%) stddev-sans-first:1.92 11:13:47 INFO - PROCESS | 1653 | Values: 163.0 164.0 162.0 164.0 160.0 166.0 164.0 163.0 165.0 161.0 11:13:47 INFO - PROCESS | 1653 | 11:13:47 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:66.50 stddev:11.84 (17.8%) stddev-sans-first:12.39 11:13:47 INFO - PROCESS | 1653 | Values: 65.0 64.0 63.0 58.0 81.0 91.0 89.0 68.0 63.0 64.0 11:13:47 INFO - PROCESS | 1653 | -------- Summary: end -------- 11:13:47 INFO - PROCESS | 1653 | 11:13:48 INFO - TEST-INFO | 1653: exit 0 11:13:48 INFO - TEST-OK | kraken | took 60259ms 11:13:48 INFO - SUITE-END | took 1176s 11:13:48 INFO - Completed test suite (00:19:36) 11:13:48 INFO - javascript benchmark 11:13:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7981.755377767625, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.933081338373, 3396.9129878438903, 3672.155404257021, 3695.4826324314586, 3722.1997270586853, 5328.5470734987575, 6220.191375121233, 6265.060240963852, 6265.3421390754, 6286.591371560845, 6198.876022479548, 6238.78164264252, 6249.437550620443, 6260.2487701475775, 6279.0, 4704.082703872747, 5287.920681189766, 5649.322081350223, 5667.744951477172, 5749.166370876221, 5726.914096288559, 5733.856653583652, 5742.885142297151, 5748.396418376073, 5777.624454410471, 5461.262729531502, 5475.397706252309, 5514.5864060195445, 5702.431831246399, 5711.657300561958, 5642.322921249435, 5659.773609055633, 5717.171010203539, 5746.942530574683, 5789.594728369016, 5552.167174923753, 5655.321361436613, 5689.971550142222, 5696.316442026942, 5722.971385143048, 6002.339742628307, 6279.780207692755, 6300.275468321156, 6314.0844577536, 6334.334894836068, 6432.035194720783, 6439.710213040377, 6461.709223084926, 6566.70449829754, 6583.769568065095, 6772.051912732221, 6788.2193547742145, 6801.591904485697, 6814.863702725967, 6824.146981627297, 6271.560990730696, 6299.842503937438, 6326.905096423558, 6354.650494222769, 6356.078368636522, 6533.085368048477, 6584.407403333723, 6633.2703402625675, 6890.552114112566, 6950.54821436605, 6470.547061705635, 6497.740090396425, 6541.47668186544, 6555.115059467006, 6561.212654481493, 5673.205751194836, 6570.915798893129, 6754.527183097136, 6783.186017677812, 6814.727410903508, 6357.11000459936, 6677.399034086956, 6691.26396096429, 6705.731770729116, 6717.328267173243, 6337.588056776295, 6449.193850768654, 6565.212174539086, 6595.241547222035, 6724.361185687352, 4476.6194873436125, 5282.864184200338, 5396.676199428047, 5443.237946687467, 5447.809326673548, 3965.980170099131, 4772.188727916263, 4775.2598347256235, 4779.211430114062, 4782.139214941274, 5276.34045744282, 5637.928793529216, 6328.493720502349, 6344.333844946306, 6349.206349206349, 5764.798232061858, 6522.445592124723, 6553.803385898431, 6608.8347791305605, 6691.966540167366, 6352.7141278642575, 6354.650494222862, 6357.713902874381, 6364.268109167505, 6375.139356186855, 6021.909671354933, 6030.33666296707, 6031.969840150771, 6048.485878700271, 6051.183090282843, 6548.639824809681, 6599.769008084886, 6856.348646878539, 6900.689468974008, 7033.085698859218, 6748.493862960396, 6775.220849602359, 6781.796546103625, 6797.6941037654415, 6815.318468153145, 6926.203486598904, 6978.371946524634, 7001.334873187039, 7004.894926576004, 7030.9296907030275, 6369.20384951881, 6418.261899881387, 6565.835854103686, 6710.094137291599, 6715.127033485615, 6691.1636045494315, 6745.12313399255, 6747.6626168692355, 6765.391114799691, 6770.390664840188, 6307.558470906993, 6565.638889860912, 6570.244421891543, 6570.8357291068105, 6575.112359831362, 6235.812925612239, 6309.684515774101, 6510.804675859732, 6597.241317248578, 6606.735730570724, 5882.264716910387, 6224.470919971853, 6457.61254324742, 6474.838129046623, 6491.935080649133, 6545.14913061299, 6549.443297319781, 6575.835604109744, 6597.967010165111, 6602.0, 6230.221222347207, 6262.997920332725, 6308.369163083655, 6328.6519241442165, 6375.139356186855], "value": 6118.285381671132, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [781.96872125115, 823.6664151018837, 843.9578021098945, 994.4480813148699, 997.5461165169849, 31960.36079278416, 41557.58442415575, 43055.13889722204, 43176.352354714625, 43313.35029974553, 41181.176376472526, 41487.37768933469, 41492.0, 41534.58465415345, 41779.79110104445, 8384.993800743903, 8522.318214542853, 11330.263532870378, 11626.953574178322, 11793.233439826385, 11485.966263036325, 11527.769444611104, 11528.250663706833, 11595.710107247301, 11603.593874214404, 11518.481668324903, 11535.942320288386, 11572.6528204154, 11575.479103440366, 11594.42027898606, 11532.0, 11535.826962595567, 11540.307581545134, 11549.884501154966, 11560.190786644938, 11276.0, 11333.886661133365, 11335.546578136864, 11342.603008894732, 11345.829812552818, 35523.11192220189, 35777.46333804995, 35903.82048089769, 35989.64010359915, 36111.458328125096, 3894.3963685628773, 3909.2181563687377, 3923.705722070856, 3974.1654252606986, 4007.3788562772816, 21573.029213685266, 23600.93795779194, 25743.584102874334, 26357.20928372152, 27880.442391152064, 3000.069978306732, 3229.9677003229904, 3277.524758909945, 3309.652486488932, 3418.2138108234994, 345041.0, 349159.0, 363149.0, 365760.0, 405793.0, 44079.0, 44398.55601444009, 44595.0, 44644.99999999935, 44654.776726116164, 35443.0, 38995.6100438992, 44847.327290090674, 46131.76934115308, 46167.0, 13701.520446784314, 13748.862511374959, 13839.308034598229, 13841.930790345983, 13842.723145537033, 11930.284182949052, 11970.40147992597, 12125.09061820367, 12187.329696866758, 12225.388730563436, 11950.88049119515, 12026.939865300792, 12044.277343359427, 12079.818802717966, 12141.635750927486, 4757.500462451393, 5680.318361796611, 5725.484706376363, 5740.1102829061565, 5741.65550066989, 1126.318577260762, 1146.1289420040662, 1147.839302497651, 1164.1967042740482, 1201.387292480841, 22937.88531057334, 26636.733632663425, 26671.19986400411, 26891.0, 26957.326066848487, 1108.51225460797, 1256.2211428914131, 1275.8150068240238, 1277.4570807406815, 1321.378951892609, 13821.963352748384, 13857.930710346383, 14143.858561414254, 14162.0, 14162.221077840817, 372648.0, 372775.0, 373538.0, 374099.0, 374561.0, 39026.80486597605, 48934.51065489371, 56199.0, 57571.71214143903, 58115.70942145262, 1481.7332880081474, 1489.3967942982893, 1525.2754941402745, 1529.3347393883696, 1533.340663514677, 25249.621255680813, 26574.369577920093, 26848.0, 26880.0, 26966.191014269603, 44060.457883973904, 44149.77925110354, 44179.77910110429, 44214.77892610645, 44339.0, 369345.0, 376329.0, 377026.0, 377746.0, 380652.0, 28186.872525098766, 28701.71298287074, 32466.513002305455, 32508.0, 33705.325893482484, 46279.0, 46513.30230046484, 46596.835079123295, 47537.049259015315, 47978.80052998703, 26096.0, 32162.356752864343, 32204.67795322017, 32229.51655725213, 32332.83833580817, 32227.516587250742, 32456.675433245364, 32613.673863262014, 34984.650153499155, 35533.466997995565, 39828.20343593054, 42079.57920420879, 42430.57569424266, 42499.787501062296, 42847.57152428436, 40716.796416018915, 41347.793261033505, 41686.791566041975, 42335.729928102206, 42348.15303693971, 27758.5836212461, 31058.185981172956, 32977.83511082429, 33057.83471082629, 33203.33593328168], "value": 19121.454589371217, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.4372019633592, 430.6705370391651, 436.556894751827, 444.39784092554606, 449.341714388421, 18574.0, 20154.294599688994, 21173.788262117392, 21616.783832161673, 21869.562608747834, 21506.247281345157, 21880.89059554704, 22209.777902220972, 22211.88940552974, 22427.77572224281, 6873.896891546631, 6888.863337549298, 7114.0, 7132.928670713279, 7133.964330178368, 6825.55633883796, 6990.860182796341, 7049.647517624098, 7055.118110236221, 7079.646017699094, 6778.254392016875, 6980.476464265175, 7038.1554213494455, 7045.11936007999, 7055.0, 6815.625140617264, 7021.262767409425, 7098.787036388891, 7104.396126329269, 7123.821904452404, 6867.828304292432, 6871.0, 6884.139482564679, 6895.413889819371, 6896.793096207071, 26682.33294167642, 27159.0, 27368.178954631392, 27669.169924902286, 28079.0, 2967.4806908790874, 3058.189579761343, 3058.816471011746, 3059.571659967606, 3087.675794041616, 16622.66754664912, 17045.318187272493, 17096.658066838718, 17261.827381726274, 17431.47705568835, 2206.4042708468623, 2225.1766846266823, 2275.795178433949, 2276.590213761522, 2291.117919600949, 120472.39763801213, 153675.2316238423, 156560.0, 158201.0, 159203.0, 21948.561028779335, 22810.8859455705, 22902.541949161256, 22906.198283060014, 23103.306900793003, 32309.67690323114, 32367.190820229684, 32991.83504082512, 33143.3371332572, 33153.83423082869, 9566.473843938651, 10778.622748203932, 10784.78430431387, 11123.666290011313, 11263.098952083814, 9162.908370916339, 9201.861972070425, 9240.5841737122, 9310.53447327631, 9346.859797103049, 9961.950190249003, 10084.949575252223, 10088.394696318097, 10211.489425528694, 10219.591216351262, 3610.693091087287, 3695.6119607440833, 4191.371294305878, 4243.533211346749, 4316.7409955402145, 842.4902933725131, 860.414917855864, 993.4039576254335, 1023.6519583341551, 1033.8914413986574, 20293.0, 21334.768139335705, 21336.0, 21380.78619213788, 21522.031508582153, 818.414833394126, 978.8972157923458, 996.8106059848606, 1006.5872992073215, 1016.4866742295242, 10810.297330673638, 11093.0, 11106.333619982826, 11114.833277500844, 11306.925842044993, 765820.0, 819908.0, 825848.0, 833575.0, 835219.0, 118392.0, 150865.0, 162346.37653623312, 163100.0, 163645.18177409275, 1189.5895915908998, 1275.859655437901, 1299.3243513372993, 1322.8412590489204, 1382.032577195967, 17553.473395798148, 17557.648847022734, 18200.635987280446, 19095.0, 19411.73823701455, 47710.284345734144, 61950.69024654848, 62377.12868613949, 62434.37565624285, 62497.68751156215, 67054.0, 75440.24559754552, 86887.56556217179, 88156.0, 88858.55570722105, 7390.556566606021, 10000.499975001076, 11333.0, 11640.35978021217, 12082.577109801116, 12912.935435322763, 13057.869421305666, 13086.14940028895, 13368.197908125543, 13425.194488330731, 11225.887741122811, 11260.774784504429, 11376.2366833141, 11520.251183673034, 11652.94173529127, 10056.44689542053, 10500.842487362848, 10563.613779315081, 10730.248882578457, 10835.187360947803, 11478.540858365906, 11576.942115289707, 11647.068234541386, 11821.862348178413, 11843.28940263587, 11193.440327983406, 11688.70778230551, 11874.168808183344, 12002.39988000614, 12055.15613907018, 11232.606858759904, 11310.660680179608, 11547.0, 11562.94218528902, 11690.76618467609], "value": 11795.784850320262, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1636.8608668263198, 1650.8349165083494, 1665.2589597629055, 1672.3477843640983, 1687.9662406751863, 11037.227394082418, 11470.967612914836, 11711.765764684702, 11759.7648047039, 11886.346250956216, 11027.724306892333, 11217.887821121787, 11233.775324493505, 11239.494222759975, 11256.099512039038, 2518.8362756420775, 2564.8845801938955, 2567.3709941064467, 2567.756063174005, 2570.3060173753074, 2280.395695140789, 2435.366804630791, 2490.414752533153, 2556.399246177147, 2568.2295311406597, 2497.7627125423055, 2543.109911530968, 2544.440223150904, 2556.322574517754, 2567.0501914291644, 2093.3929160543425, 2102.190656597206, 2375.4061484628755, 2412.9034838606435, 2413.613821788514, 2197.8021978021893, 2446.3150317911013, 2537.8350407223656, 2547.261294224673, 2597.948041039169, 11542.538298468051, 11627.0, 11780.410979451079, 11789.941050294694, 11800.704982375424, 6127.662978536178, 6206.534509911775, 6212.9068063979075, 6226.2839773426185, 6227.688615569204, 3821.0577855051733, 3833.9424908626393, 3862.6909847212155, 3925.6074392560517, 3955.1101002274545, 1249.056961993689, 1269.0165122030407, 1282.7947528395505, 1300.1873828856965, 1311.7114234868313, 14313.069650472686, 14618.780718289234, 15520.301586428637, 16197.91901040499, 16307.184640768033, 4212.0, 4887.3890763654545, 4913.410390753133, 5061.1902095665255, 5078.933423980931, 12175.330356830282, 12376.381180940916, 12381.690457738629, 12383.133180677267, 12410.379481025913, 6161.507079433634, 6161.784337548164, 6165.290991535941, 6173.444390004831, 6179.721912513947, 4713.6936099153445, 5481.314835645544, 5598.664080155204, 5599.916001259984, 5624.21261023457, 4080.227083774017, 4142.855000075043, 4144.088300573869, 4162.7502349859105, 4181.310083836193, 992.6972273456665, 1044.206403133624, 1097.0455703537814, 1107.379867274329, 1110.7556337605713, 827.437342607033, 963.3882484622176, 972.8881178664402, 981.2983716642635, 1002.9147522460527, 6834.8633027340165, 6863.725450981905, 6883.3116688330765, 7055.647217639097, 7095.503314767917, 1013.5641674080216, 1054.7468607534136, 1055.5355643516982, 1056.529844219315, 1082.8429877667852, 4381.978090109593, 4388.5830846069575, 4402.515723270438, 4414.337849322563, 4511.887202819956, 12478.0, 13830.86169138316, 14025.719485610434, 14418.639534011523, 14474.203918784568, 13432.731345373038, 13946.0, 13946.442142314396, 13948.093373930662, 14347.56957291283, 8845.4250473719, 9003.099690031075, 10343.27597068213, 10610.628627997983, 10683.732906677395, 5615.859603509945, 7158.105236845395, 7228.0, 7257.455690823315, 7366.300201480851, 12348.197367171106, 12496.375181241083, 12511.311877846805, 12960.157589756636, 13117.409716562968, 10723.30298530593, 10729.463526823783, 13118.212907225598, 13265.270410127536, 13310.866891330963, 8647.56762161902, 9080.091990800867, 9491.762705932408, 9552.90447095548, 9718.805623887623, 10605.787884242427, 10655.57377704883, 10684.46577671129, 10771.246012779344, 10911.672649820519, 9163.358564900393, 9272.953635231781, 9282.071792820663, 9304.767380815534, 9401.0, 9028.774280643036, 9047.411918225294, 9062.365634405793, 9095.181433671001, 9126.77183070402, 8317.875231871647, 8360.12218717026, 8479.872801908099, 8486.83026339482, 8500.404971651926, 6039.516838652985, 6064.484518815773, 6071.666058366833, 6170.7223174958135, 6250.1874756281395, 6110.511159107172, 6212.285587157535, 6239.282482514569, 6315.905261421174, 6318.968405157945], "value": 5346.409011671866, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.2845349916653, 400.80759739774174, 404.61561516559266, 404.78950945508325, 405.8721502726641, 4514.268867335264, 5443.210734443508, 5540.612157149001, 5549.694766787825, 5558.110242517549, 4340.826366945318, 4965.226427914784, 5278.287431196787, 5290.6140070355, 5354.866128346794, 4394.6923715339935, 4396.098799746044, 4413.514513403531, 4414.381986521874, 4422.867313980586, 3929.6659783918403, 3971.6028397160344, 4471.597556219939, 4490.41624588803, 4509.7970591323465, 3727.384981478053, 3757.567879693829, 4245.34197199433, 4448.755318457484, 4474.574915383034, 3750.412215571247, 4128.4632997710105, 4338.934915976247, 4390.934135987963, 4439.88900277494, 3575.821208939569, 3878.2437424702193, 3917.5494818096, 3953.9802300988313, 4093.5175982299634, 4700.038975445482, 4832.9758351208375, 5219.781592823073, 5227.477252274742, 5238.266642670029, 4617.37665415168, 4950.0, 5010.398752149729, 5051.848444546669, 5194.532492075694, 5113.437521872591, 5292.708901010443, 5693.544516438675, 5719.197593179312, 5811.506021988136, 3936.5079365079364, 4236.364545318227, 4466.285394336891, 4595.908081838378, 4602.930956035662, 4582.03777206787, 5049.364457590529, 5945.640712997143, 5997.490213331872, 6024.789132380346, 4076.8584796257096, 4513.616342610849, 4723.385959825201, 4735.526447355237, 4758.0, 4314.56854314566, 4615.3999980003055, 4766.594839438617, 4815.858361808841, 4866.926996095061, 4540.480033581328, 4867.61059115277, 5124.487551244845, 5156.988774377921, 5182.844514664566, 4643.281985665325, 5053.46938571452, 5377.784888604412, 5381.650192737459, 5697.205380305273, 1434.2470203143225, 1553.9766903496457, 1569.6546759712844, 1612.057470747746, 1623.8944468609502, 1066.8879767624444, 1067.588978243382, 1124.9156313276537, 1200.6218041316956, 1231.901447884167, 4755.215389460769, 4864.805407783649, 4949.48030456804, 5026.698398096126, 5448.482394172547, 5951.0, 5960.344362120163, 5970.880582388328, 5993.760249589967, 6005.036675793955, 4387.758673273001, 4618.792154353062, 5253.4221235664045, 5313.847891603907, 5436.70098144598, 2721.8840275486955, 2753.6695596528543, 2788.0381268462347, 3097.2102113960796, 3143.281061881502, 5333.359996800409, 5903.2325797646035, 5933.970330148321, 6076.939230607726, 6156.7070915108325, 5456.781728730806, 5621.437856214346, 5762.86773893662, 6061.514928842462, 6116.440307721503, 5307.99148161848, 5987.520998320037, 6004.579679422399, 6150.292716337589, 6153.292371377349, 4957.851264462072, 5924.407559244041, 5929.644221346733, 5935.970320148372, 5994.820155395345, 5297.496737809902, 5612.494875461228, 5697.4587414195585, 5877.441643043904, 5879.676617786063, 5173.793048278027, 5210.843674689765, 5885.529157667462, 5987.5330544017, 6106.603070800384, 3941.408788681606, 4312.878651550556, 4315.532718875597, 4612.354270402079, 4613.284940834176, 4904.681195722266, 4935.8025678972435, 5144.742762861767, 5518.91721624184, 5632.802851900164, 5154.407243167084, 5255.214736189825, 5579.0, 5781.306243250669, 5801.274840644919, 4819.49395313487, 5506.614536982533, 5702.401247868914, 5814.604494921273, 5934.495567876779, 5058.735316170957, 5306.204069389622, 5340.973295133499, 5544.532682956045, 5697.54419646419, 4980.452150263396, 5175.835437026639, 5692.0, 5770.0, 5835.586182083529, 4804.615630749462, 4967.776450059829, 5454.3454785426, 5699.458551437607, 5705.258316418839], "value": 4328.415854204042, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.9325060744532, 763.4236151705461, 818.1041759273593, 845.7885528617845, 883.5758835758836, 16408.26162822673, 17437.040962747044, 17474.126293685313, 17513.299468021265, 17543.912280438595, 13499.39252733627, 15880.126593037377, 16008.83991160088, 16061.83938160618, 16065.518034458984, 946.8059047895182, 965.1410244882061, 969.2149359019181, 969.8108868770593, 976.623999760094, 999.7850462150636, 1000.104906109032, 1008.7730260691359, 1011.6964910526849, 1012.0132870451324, 1060.9204309676766, 1062.2511129653615, 1062.3891262524041, 1064.0264158295151, 1064.4571268653003, 1035.1149766949263, 1052.610534102387, 1052.973675658107, 1053.8261186904151, 1054.8206804843157, 743.2344684974485, 770.245159743449, 782.4561929459028, 785.5797148525511, 786.5831109511968, 15964.840351596451, 17298.048607326593, 17626.91186544059, 17968.460946171635, 18305.7254141188, 14682.55952321432, 14813.037152585048, 14847.331870065873, 15052.924735376362, 15199.392024319013, 12074.922350571604, 12147.149699521036, 12338.938305308506, 12340.568080117153, 12954.935225323907, 3849.653531182207, 3889.183271512986, 3947.0, 3980.6218409251073, 4089.672826173899, 114910.27634585487, 115523.0, 118711.8128818718, 122175.0, 123933.0, 11103.5558577656, 11237.157213209042, 11477.942610286895, 11732.12009099304, 12213.75572488563, 24057.75942240555, 24404.145854894992, 24404.389890252885, 24431.14490992807, 24503.38741531476, 8295.505561929418, 8310.459820111673, 8413.537255451009, 8422.452540584842, 8467.61895714682, 4573.176828170895, 4604.539546045368, 4675.122806667926, 4695.835645752382, 4847.878803029953, 3683.4290684943876, 3744.6817020553012, 3764.4917936078277, 3795.6014618465215, 3797.468354430382, 655.2890114226022, 706.2866504830056, 736.1203361982487, 746.0748671647118, 749.6813854111981, 632.7595513704855, 713.6360456167392, 723.9131304243524, 736.2747693521877, 743.4349894080538, 9563.713088607354, 11013.83479247812, 11047.558097676003, 11085.72285692848, 11261.943690281496, 696.9756058538028, 719.7768691705588, 731.1737736357882, 732.1653315220652, 752.8419032003231, 5148.489300035753, 5212.296339994128, 5223.955208958224, 5224.320838290998, 5288.101022826128, 74088.88866667004, 99836.0, 107763.46118269359, 108189.0, 110231.0, 94772.0, 95769.5634565482, 95898.52050739701, 97282.5135874316, 97949.51025244828, 80996.59501702453, 81298.59350703208, 83489.58255208685, 84150.37172955424, 84762.30475390458, 10158.949205253926, 10319.432431216055, 10321.535530901278, 10346.689599312032, 10380.0, 47936.7603161982, 48865.0, 50300.49699503105, 52805.47194528006, 54227.45772542224, 67654.3234567648, 68505.0, 72181.91727123992, 72224.63887680529, 72874.0, 13289.80065299002, 13332.666683332995, 13484.0, 13777.862221377658, 13984.091034082752, 15224.391024358902, 15370.846291536942, 15543.766843497582, 15665.216739163225, 16285.941413808065, 14110.0, 14412.711745764816, 14680.0, 14705.33825977855, 15282.312295946487, 14030.298485075502, 14596.197209153173, 14711.779323309946, 14730.410783568537, 15026.323815428113, 14172.929135354258, 14263.944524692188, 14297.35661895238, 14390.352434140279, 14393.424263029361, 9005.369624126248, 9897.703068907944, 10264.794704106025, 10353.0, 10545.0, 8982.579330248856, 9854.0, 11599.420028998686, 11601.477933493183, 11758.764824703629], "value": 8096.225136851875, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 122.0, 119.0, 124.0, 178.0, 180.0, 124.0, 118.0, 121.0, 115.0], "value": 131.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 120.0, 111.0, 145.0, 109.0, 109.0, 108.0, 111.0, 126.0, 111.0], "value": 119.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 198.0, 199.0, 193.0, 198.0, 194.0, 198.0, 198.0, 196.0, 196.0], "value": 196.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 59.0, 86.0, 60.0, 76.0, 83.0, 83.0, 73.0, 60.0], "value": 72.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 71.0, 84.0, 73.0, 72.0, 68.0, 79.0, 69.0, 70.0, 82.0], "value": 74.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 135.0, 136.0, 89.0, 88.0, 87.0, 93.0, 92.0, 136.0, 137.0], "value": 106.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 95.0, 95.0, 101.0, 150.0, 95.0, 96.0, 96.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 95.0, 138.0, 96.0, 142.0, 140.0, 89.0, 93.0, 92.0, 90.0], "value": 106.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 67.0, 69.0, 68.0, 70.0, 69.0, 69.0, 68.0], "value": 67.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 59.0, 62.0, 99.0, 93.0, 88.0, 57.0, 58.0, 60.0, 60.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 98.0, 101.0, 98.0, 102.0, 99.0, 101.0, 76.0, 82.0, 87.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 139.0, 151.0, 153.0, 127.0, 141.0, 155.0, 150.0, 189.0, 149.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 162.0, 164.0, 160.0, 166.0, 164.0, 163.0, 165.0, 161.0], "value": 163.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 63.0, 58.0, 81.0, 91.0, 89.0, 68.0, 63.0, 64.0], "value": 70.6, "unit": "score"}]}]} 11:13:48 INFO - Return code: 0 11:13:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:13:48 ERROR - # TBPL SUCCESS # 11:13:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:13:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:13:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:13:48 INFO - 2017-05-25 11:13:48.985 screenresolution[1658:10638] starting screenresolution argv=screenresolution get 11:13:48 INFO - 2017-05-25 11:13:48.988 screenresolution[1658:10638] Display 0: 1600x1200x32@50 11:13:48 INFO - 2017-05-25 11:13:48.995 screenresolution[1659:10641] starting screenresolution argv=screenresolution list 11:13:48 INFO - Available Modes on Display 0 11:13:48 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 11:13:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:13:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:13:48 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 11:13:48 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 11:13:49 INFO - Graphics/Displays: 11:13:49 INFO - Intel Iris: 11:13:49 INFO - Chipset Model: Intel Iris 11:13:49 INFO - Type: GPU 11:13:49 INFO - Bus: Built-In 11:13:49 INFO - VRAM (Dynamic, Max): 1536 MB 11:13:49 INFO - Vendor: Intel (0x8086) 11:13:49 INFO - Device ID: 0x0a2e 11:13:49 INFO - Revision ID: 0x0009 11:13:49 INFO - Displays: 11:13:49 INFO - Display: 11:13:49 INFO - Resolution: 1600 x 1200 @ 50 Hz 11:13:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:13:49 INFO - Display Serial Number: 1600x1200 50 11:13:49 INFO - Main Display: Yes 11:13:49 INFO - Mirror: Off 11:13:49 INFO - Online: Yes 11:13:49 INFO - Rotation: Supported 11:13:49 INFO - Return code: 0 11:13:49 INFO - Running post-action listener: _resource_record_post_action 11:13:49 INFO - [mozharness: 2017-05-25 18:13:49.228494Z] Finished run-tests step (success) 11:13:49 INFO - Running post-run listener: _resource_record_post_run 11:13:49 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 44275712; Write bytes: 424514560; Read time: 480; Write time: 1662 11:13:49 INFO - TinderboxPrint: CPU usage
54.4% 11:13:49 INFO - TinderboxPrint: I/O read bytes / time
44,275,712 / 480 11:13:49 INFO - TinderboxPrint: I/O write bytes / time
424,514,560 / 1,662 11:13:49 INFO - TinderboxPrint: CPU idle
2,174.2 (45.6%) 11:13:49 INFO - TinderboxPrint: CPU system
953.1 (20.0%) 11:13:49 INFO - TinderboxPrint: CPU user
1,642.3 (34.4%) 11:13:49 INFO - TinderboxPrint: Swap in / out
160,788,480 / 0 11:13:49 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123279872; Write bytes: 122884096; Read time: 11142; Write time: 240 11:13:49 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 41904128; Write bytes: 300196864; Read time: 452; Write time: 1416 11:13:49 INFO - Running post-run listener: _upload_blobber_files 11:13:49 INFO - Blob upload gear active. 11:13:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:13:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:13:50 INFO - (blobuploader) - INFO - Open directory for files ... 11:13:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:13:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:13:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:50 INFO - (blobuploader) - INFO - Done attempting. 11:13:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:13:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:13:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:51 INFO - (blobuploader) - INFO - Done attempting. 11:13:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:13:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:13:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:52 INFO - (blobuploader) - INFO - Done attempting. 11:13:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:13:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:13:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:54 INFO - (blobuploader) - INFO - Done attempting. 11:13:54 INFO - (blobuploader) - INFO - Iteration through files over. 11:13:54 INFO - Return code: 0 11:13:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:13:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:13:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fcffb4f0a038db75015c22ab716fbe1f8c5b48b32cf80937dd599e351f2cba33c1eec9d4534e61070581737584761022f106cc75e2e534ae4b76074dbe50572b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/212ca73226d2af93e56dfacfcfdd9168dd79aca63f11db1be6c2ca12b1068ae5ae6af1b27aff3ef9ba014f4a6ece5a56474332f7028703d7d5fa617bf2ee1974", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f3e487262dcc984ec735c58736fd43a816a18b32fa29dda6e538f5317e4c23692bea95269e258c3060a9c53ab2f64eb1a7b95914760fa9f9635b0c7c3a967756", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2743b1d436f0102df6c77ba79096f070190bdb634125e6bac9a4b2a8dc1e1f337ac44b110519d40f55ad1d5bb51d64c2dd7a0ac29eaa29081fc98a400998eea3"} 11:13:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:13:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:13:54 INFO - Contents: 11:13:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fcffb4f0a038db75015c22ab716fbe1f8c5b48b32cf80937dd599e351f2cba33c1eec9d4534e61070581737584761022f106cc75e2e534ae4b76074dbe50572b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/212ca73226d2af93e56dfacfcfdd9168dd79aca63f11db1be6c2ca12b1068ae5ae6af1b27aff3ef9ba014f4a6ece5a56474332f7028703d7d5fa617bf2ee1974", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f3e487262dcc984ec735c58736fd43a816a18b32fa29dda6e538f5317e4c23692bea95269e258c3060a9c53ab2f64eb1a7b95914760fa9f9635b0c7c3a967756", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2743b1d436f0102df6c77ba79096f070190bdb634125e6bac9a4b2a8dc1e1f337ac44b110519d40f55ad1d5bb51d64c2dd7a0ac29eaa29081fc98a400998eea3"} 11:13:54 INFO - Running post-run listener: copy_logs_to_upload_dir 11:13:54 INFO - Copying logs to upload dir... 11:13:54 INFO - mkdir: /builds/slave/test/build/upload/logs 11:13:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.667839 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-05-25 11:13:54.816808) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 11:13:54.820383) ========= 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.NM3AyOhCc4/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.Q1fu17si7P/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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/fcffb4f0a038db75015c22ab716fbe1f8c5b48b32cf80937dd599e351f2cba33c1eec9d4534e61070581737584761022f106cc75e2e534ae4b76074dbe50572b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/212ca73226d2af93e56dfacfcfdd9168dd79aca63f11db1be6c2ca12b1068ae5ae6af1b27aff3ef9ba014f4a6ece5a56474332f7028703d7d5fa617bf2ee1974", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f3e487262dcc984ec735c58736fd43a816a18b32fa29dda6e538f5317e4c23692bea95269e258c3060a9c53ab2f64eb1a7b95914760fa9f9635b0c7c3a967756", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2743b1d436f0102df6c77ba79096f070190bdb634125e6bac9a4b2a8dc1e1f337ac44b110519d40f55ad1d5bb51d64c2dd7a0ac29eaa29081fc98a400998eea3"} build_url:https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011324 build_url: 'https://queue.taskcluster.net/v1/task/QhCVXicTSgufn7JmsOkvFw/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/fcffb4f0a038db75015c22ab716fbe1f8c5b48b32cf80937dd599e351f2cba33c1eec9d4534e61070581737584761022f106cc75e2e534ae4b76074dbe50572b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/212ca73226d2af93e56dfacfcfdd9168dd79aca63f11db1be6c2ca12b1068ae5ae6af1b27aff3ef9ba014f4a6ece5a56474332f7028703d7d5fa617bf2ee1974", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f3e487262dcc984ec735c58736fd43a816a18b32fa29dda6e538f5317e4c23692bea95269e258c3060a9c53ab2f64eb1a7b95914760fa9f9635b0c7c3a967756", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2743b1d436f0102df6c77ba79096f070190bdb634125e6bac9a4b2a8dc1e1f337ac44b110519d40f55ad1d5bb51d64c2dd7a0ac29eaa29081fc98a400998eea3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 11:13:54.853939) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 11:13:54.854253) ========= 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.NM3AyOhCc4/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.Q1fu17si7P/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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.005068 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 11:13:54.902486) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 11:13:54.902762) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 11:13:54.903062) ========= ========= Total master_lag: 0.17 =========