builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0383 starttime: 1497374495.13 results: success (0) revision: 02caa113457b2780c13bf0d7c26cb739558b5f5b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 10:21:35.135371) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 10:21:35.136137) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 10:21:35.136407) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 10:21:35.160712) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:21:35.161329) ========= 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.6jG53hyTO2/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.XVNaxBLO2m/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-13 10:21:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 50.5M=0s 2017-06-13 10:21:35 (50.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125461 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:21:35.309648) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:21:35.309974) ========= 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.6jG53hyTO2/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.XVNaxBLO2m/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.021401 ========= master_lag: 0.28 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:21:35.616017) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-13 10:21:35.616347) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 02caa113457b2780c13bf0d7c26cb739558b5f5b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 02caa113457b2780c13bf0d7c26cb739558b5f5b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6jG53hyTO2/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.XVNaxBLO2m/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-13 10:21:35,716 truncating revision to first 12 chars 2017-06-13 10:21:35,716 Setting DEBUG logging. 2017-06-13 10:21:35,716 attempt 1/10 2017-06-13 10:21:35,716 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/02caa113457b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 10:21:37,621 unpacking tar archive at: mozilla-beta-02caa113457b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.171364 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-13 10:21:37.810099) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 10:21:37.810494) ========= script_repo_revision: 02caa113457b2780c13bf0d7c26cb739558b5f5b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 10:21:37.810986) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 10:21:37.811337) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 10:21:37.830207) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2017-06-13 10:21:37.830526) ========= /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.6jG53hyTO2/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.XVNaxBLO2m/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:21:38 INFO - MultiFileLogger online at 20170613 10:21:38 in /builds/slave/test 10:21:38 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:21:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:21:38 INFO - {'append_to_log': False, 10:21:38 INFO - 'base_work_dir': '/builds/slave/test', 10:21:38 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:21:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:21:38 INFO - 'branch': 'Mozilla-Beta', 10:21:38 INFO - 'buildbot_json_path': 'buildprops.json', 10:21:38 INFO - 'config_files': ('talos/mac_config.py',), 10:21:38 INFO - 'default_actions': ('clobber', 10:21:38 INFO - 'read-buildbot-config', 10:21:38 INFO - 'download-and-extract', 10:21:38 INFO - 'populate-webroot', 10:21:38 INFO - 'create-virtualenv', 10:21:38 INFO - 'install', 10:21:38 INFO - 'setup-mitmproxy', 10:21:38 INFO - 'run-tests'), 10:21:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:21:38 INFO - 'download_minidump_stackwalk': True, 10:21:38 INFO - 'download_symbols': 'ondemand', 10:21:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:21:38 INFO - 'tooltool.py': '/tools/tooltool.py', 10:21:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:21:38 INFO - '/tools/misc-python/virtualenv.py')}, 10:21:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:21:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:21:38 INFO - 'gecko_profile': False, 10:21:38 INFO - 'gecko_profile_interval': 0, 10:21:38 INFO - 'installer_path': 'installer.exe', 10:21:38 INFO - 'log_level': 'info', 10:21:38 INFO - 'log_name': 'talos', 10:21:38 INFO - 'log_to_console': True, 10:21:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:21:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:21:38 INFO - 'opt_config_files': (), 10:21:38 INFO - 'pip_index': False, 10:21:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:21:38 INFO - 'cmd': ('bash', 10:21:38 INFO - '-c', 10:21:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:21:38 INFO - 'enabled': True, 10:21:38 INFO - 'halt_on_failure': False, 10:21:38 INFO - 'name': 'check_screen_resolution'},), 10:21:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:21:38 INFO - 'cmd': ('bash', 10:21:38 INFO - '-c', 10:21:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:21:38 INFO - 'enabled': True, 10:21:38 INFO - 'halt_on_failure': False, 10:21:38 INFO - 'name': 'check_screen_resolution'},), 10:21:38 INFO - 'run_cmd_checks_enabled': True, 10:21:38 INFO - 'suite': 'dromaeojs-e10s', 10:21:38 INFO - 'system_bits': '32', 10:21:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:21:38 INFO - 'title': 't-yosemite-r7-0383', 10:21:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:21:38 INFO - 'use_talos_json': True, 10:21:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:21:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:21:38 INFO - 'work_dir': 'build'} 10:21:38 INFO - [mozharness: 2017-06-13 17:21:38.051691Z] Running clobber step. 10:21:38 INFO - Running pre-action listener: _resource_record_pre_action 10:21:38 INFO - Running main action method: clobber 10:21:38 INFO - rmtree: /builds/slave/test/build 10:21:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:21:38 INFO - Running post-action listener: _resource_record_post_action 10:21:38 INFO - [mozharness: 2017-06-13 17:21:38.703522Z] Finished clobber step (success) 10:21:38 INFO - [mozharness: 2017-06-13 17:21:38.703697Z] Running read-buildbot-config step. 10:21:38 INFO - Running pre-action listener: _resource_record_pre_action 10:21:38 INFO - Running main action method: read_buildbot_config 10:21:38 INFO - Using buildbot properties: 10:21:38 INFO - { 10:21:38 INFO - "project": "", 10:21:38 INFO - "product": "firefox", 10:21:38 INFO - "who": "ffxbld@noreply.mozilla.org", 10:21:38 INFO - "installer_path": "public/build/firefox-55.0.en-US.mac.dmg", 10:21:38 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 10:21:38 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 10:21:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 10:21:38 INFO - "stage_platform": "macosx64", 10:21:38 INFO - "basedir": "/builds/slave/test", 10:21:38 INFO - "buildnumber": 2, 10:21:38 INFO - "platform": "yosemite_r7", 10:21:38 INFO - "parent_task_id": "KMxxOEyxQbWC71xV6BIJrQ", 10:21:38 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 10:21:38 INFO - "slavebuilddir": "test", 10:21:38 INFO - "taskId": "ds--mZtNTF6gOde4zdCUzg", 10:21:38 INFO - "branch": "mozilla-beta", 10:21:38 INFO - "script_repo_revision": "production", 10:21:38 INFO - "revision": "02caa113457b2780c13bf0d7c26cb739558b5f5b", 10:21:38 INFO - "slavename": "t-yosemite-r7-0383", 10:21:38 INFO - "repo_path": "releases/mozilla-beta" 10:21:38 INFO - } 10:21:38 INFO - Finding installer, test and symbols from parent task. 10:21:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ds--mZtNTF6gOde4zdCUzg'}, attempt #1 10:21:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ds--mZtNTF6gOde4zdCUzg'}, attempt #1 10:21:39 INFO - Task dependencies: ZXmC_tezQ-GMPd_NaCjqwA KMxxOEyxQbWC71xV6BIJrQ 10:21:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ'}, attempt #1 10:21:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:21:40 INFO - Set test_url: https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/test_packages.json 10:21:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/target.crashreporter-symbols.zip 10:21:40 INFO - Running post-action listener: _resource_record_post_action 10:21:40 INFO - [mozharness: 2017-06-13 17:21:40.549413Z] Finished read-buildbot-config step (success) 10:21:40 INFO - [mozharness: 2017-06-13 17:21:40.549558Z] Running download-and-extract step. 10:21:40 INFO - Running pre-action listener: _resource_record_pre_action 10:21:40 INFO - Running main action method: download_and_extract 10:21:40 INFO - mkdir: /builds/slave/test/build/tests 10:21:40 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:21:40 INFO - trying https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 10:21:40 INFO - Downloading https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 10:21:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 10:21:41 INFO - Downloaded 1290 bytes. 10:21:41 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 10:21:41 INFO - Using the following test package requirements: 10:21:41 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:21:41 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 10:21:41 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 10:21:41 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:21:41 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 10:21:41 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:21:41 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 10:21:41 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:21:41 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:21:41 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 10:21:41 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 10:21:41 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:21:41 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 10:21:41 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:21:41 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 10:21:41 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:21:41 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 10:21:41 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:21:41 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 10:21:41 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 10:21:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 10:21:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 10:21:41 INFO - Fetch https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 10:21:42 INFO - Content-Length response header: 58981274 10:21:42 INFO - Bytes received: 58981274 10:22:01 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 10:22:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 10:22:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 10:22:01 INFO - Fetch https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 10:22:03 INFO - Content-Length response header: 58981274 10:22:03 INFO - Bytes received: 58981274 10:22:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 10:22:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 10:22:20 INFO - Fetch https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 10:22:21 INFO - Content-Length response header: 14450018 10:22:21 INFO - Bytes received: 14450018 10:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:22 INFO - trying https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:22:22 INFO - Downloading https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 10:22:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 10:22:23 INFO - Downloaded 51079238 bytes. 10:22:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:22:23 INFO - mkdir: /builds/slave/test/properties 10:22:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:22:23 INFO - Writing to file /builds/slave/test/properties/build_url 10:22:23 INFO - Contents: 10:22:23 INFO - build_url:https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:22:23 INFO - Running post-action listener: _resource_record_post_action 10:22:23 INFO - Running post-action listener: set_extra_try_arguments 10:22:23 INFO - [mozharness: 2017-06-13 17:22:23.914757Z] Finished download-and-extract step (success) 10:22:23 INFO - [mozharness: 2017-06-13 17:22:23.914902Z] Running populate-webroot step. 10:22:23 INFO - Running pre-action listener: _resource_record_pre_action 10:22:23 INFO - Running main action method: populate_webroot 10:22:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:22:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:22:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:22:23 INFO - '--tppagecycles', 10:22:23 INFO - '7'], 10:22:23 INFO - 'tests': ['tsvgm']}}, 10:22:23 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:22:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:22:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:22:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:22:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:22:23 INFO - 'talos_options': ['--disable-e10s'], 10:22:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:22:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:22:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:22:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:22:23 INFO - 'talos_options': ['--disable-e10s'], 10:22:23 INFO - 'tests': ['damp', 'tps']}, 10:22:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:22:23 INFO - 'tests': ['damp', 'tps']}, 10:22:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:22:23 INFO - 'tests': ['dromaeo_dom']}, 10:22:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:22:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:22:23 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:22:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:22:23 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:22:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:22:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:22:23 INFO - 'tests': ['a11yr', 10:22:23 INFO - 'ts_paint', 10:22:23 INFO - 'tpaint', 10:22:23 INFO - 'sessionrestore', 10:22:23 INFO - 'sessionrestore_no_auto_restore', 10:22:23 INFO - 'tabpaint']}, 10:22:23 INFO - 'other-e10s': {'tests': ['a11yr', 10:22:23 INFO - 'ts_paint', 10:22:23 INFO - 'tpaint', 10:22:23 INFO - 'sessionrestore', 10:22:23 INFO - 'sessionrestore_no_auto_restore', 10:22:23 INFO - 'tabpaint']}, 10:22:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:22:23 INFO - 'ts_paint', 10:22:23 INFO - 'tpaint', 10:22:23 INFO - 'sessionrestore', 10:22:23 INFO - 'sessionrestore_no_auto_restore', 10:22:23 INFO - 'tabpaint']}, 10:22:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:22:23 INFO - 'ts_paint', 10:22:23 INFO - 'tpaint', 10:22:23 INFO - 'sessionrestore', 10:22:23 INFO - 'sessionrestore_no_auto_restore', 10:22:23 INFO - 'tabpaint']}, 10:22:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:22:23 INFO - 'tests': ['a11yr', 10:22:23 INFO - 'ts_paint', 10:22:23 INFO - 'tpaint', 10:22:23 INFO - 'sessionrestore', 10:22:23 INFO - 'sessionrestore_no_auto_restore', 10:22:23 INFO - 'tabpaint']}, 10:22:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:22:23 INFO - 'tests': ['a11yr', 10:22:23 INFO - 'ts_paint', 10:22:23 INFO - 'tpaint', 10:22:23 INFO - 'sessionrestore', 10:22:23 INFO - 'sessionrestore_no_auto_restore', 10:22:23 INFO - 'tabpaint']}, 10:22:23 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:22:23 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:22:23 INFO - 'bloom_basic_ref']}, 10:22:23 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:22:23 INFO - 'talos_options': ['--mitmproxy', 10:22:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:22:23 INFO - '--firstNonBlankPaint'], 10:22:23 INFO - 'tests': ['quantum_pageload_google', 10:22:23 INFO - 'quantum_pageload_youtube', 10:22:23 INFO - 'quantum_pageload_amazon', 10:22:23 INFO - 'quantum_pageload_facebook']}, 10:22:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:22:23 INFO - 'tests': ['tsvgx', 10:22:23 INFO - 'tsvgr_opacity', 10:22:23 INFO - 'tart', 10:22:23 INFO - 'tscrollx', 10:22:23 INFO - 'cart', 10:22:23 INFO - 'tsvg_static']}, 10:22:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:22:23 INFO - 'tsvgr_opacity', 10:22:23 INFO - 'tart', 10:22:23 INFO - 'tscrollx', 10:22:23 INFO - 'cart', 10:22:23 INFO - 'tsvg_static']}, 10:22:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:22:23 INFO - 'talos_options': ['--disable-e10s'], 10:22:23 INFO - 'tests': ['tp5o']}, 10:22:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:22:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:22:23 INFO - 'talos_options': ['--disable-e10s', 10:22:23 INFO - '--xperf_path', 10:22:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:22:23 INFO - 'tests': ['tp5n']}, 10:22:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:22:23 INFO - 'talos_options': ['--xperf_path', 10:22:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:22:23 INFO - 'tests': ['tp5n']}}, 10:22:23 INFO - 'talos.zip': {'path': '', 10:22:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:22:23 INFO - Running post-action listener: _resource_record_post_action 10:22:23 INFO - [mozharness: 2017-06-13 17:22:23.937918Z] Finished populate-webroot step (success) 10:22:23 INFO - [mozharness: 2017-06-13 17:22:23.938060Z] Running create-virtualenv step. 10:22:23 INFO - Running pre-action listener: _resource_record_pre_action 10:22:23 INFO - Running main action method: create_virtualenv 10:22:23 INFO - Creating virtualenv /builds/slave/test/build/venv 10:22:23 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:22:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:22:23 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:22:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:22:24 INFO - Using real prefix '/tools/python27' 10:22:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:22:24 INFO - Installing distribute.............................................................................................................................................................................................done. 10:22:27 INFO - Installing pip.................done. 10:22:27 INFO - Return code: 0 10:22:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:22:27 INFO - Reading from file tmpfile_stdout 10:22:27 INFO - Output received: 10:22:27 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:22:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:22: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:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22: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:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a582c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a586768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc51488510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2d8d30>, '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 0x10a2a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c9be8>, '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.6jG53hyTO2/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.XVNaxBLO2m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:22:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:22:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jG53hyTO2/Render', 10:22:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:27 INFO - 'HOME': '/Users/cltbld', 10:22:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:27 INFO - 'LOGNAME': 'cltbld', 10:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:27 INFO - 'MOZ_NO_REMOTE': '1', 10:22:27 INFO - 'NO_EM_RESTART': '1', 10:22:27 INFO - 'PAGER': '/bin/cat', 10:22:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:27 INFO - 'PWD': '/builds/slave/test', 10:22:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:27 INFO - 'SHELL': '/bin/bash', 10:22:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVNaxBLO2m/Listeners', 10:22:27 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 10:22:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:27 INFO - 'USER': 'cltbld', 10:22:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:27 INFO - 'XPC_FLAGS': '0x0', 10:22:27 INFO - 'XPC_SERVICE_NAME': '0', 10:22:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:28 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:22:28 INFO - Cleaning up... 10:22:28 INFO - Return code: 0 10:22:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:22:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:28 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 0x10a582c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a586768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc51488510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2d8d30>, '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 0x10a2a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c9be8>, '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.6jG53hyTO2/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.XVNaxBLO2m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:22:28 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:22:28 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:22:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jG53hyTO2/Render', 10:22:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:28 INFO - 'HOME': '/Users/cltbld', 10:22:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:28 INFO - 'LOGNAME': 'cltbld', 10:22:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:28 INFO - 'MOZ_NO_REMOTE': '1', 10:22:28 INFO - 'NO_EM_RESTART': '1', 10:22:28 INFO - 'PAGER': '/bin/cat', 10:22:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:28 INFO - 'PWD': '/builds/slave/test', 10:22:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:28 INFO - 'SHELL': '/bin/bash', 10:22:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVNaxBLO2m/Listeners', 10:22:28 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 10:22:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:28 INFO - 'USER': 'cltbld', 10:22:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:28 INFO - 'XPC_FLAGS': '0x0', 10:22:28 INFO - 'XPC_SERVICE_NAME': '0', 10:22:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:28 INFO - Downloading/unpacking psutil>=3.1.1 10:22:28 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:22:28 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:22:28 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:22:28 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:22:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:22:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:22:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:22:30 INFO - Installing collected packages: psutil 10:22:30 INFO - Running setup.py install for psutil 10:22:30 INFO - building 'psutil._psutil_osx' extension 10:22:30 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:22:30 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:22:30 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:22:30 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:22:30 INFO - building 'psutil._psutil_posix' extension 10:22:30 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:22:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:22:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:22:31 INFO - ^ 10:22:31 INFO - 1 warning generated. 10:22:31 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:22:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:22:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:22:31 INFO - Successfully installed psutil 10:22:31 INFO - Cleaning up... 10:22:31 INFO - Return code: 0 10:22:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:22:31 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:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:31 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:22:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:31 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 0x10a582c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a586768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc51488510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2d8d30>, '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 0x10a2a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c9be8>, '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.6jG53hyTO2/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.XVNaxBLO2m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:22:31 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:22:31 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:22:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jG53hyTO2/Render', 10:22:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:31 INFO - 'HOME': '/Users/cltbld', 10:22:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:31 INFO - 'LOGNAME': 'cltbld', 10:22:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:31 INFO - 'MOZ_NO_REMOTE': '1', 10:22:31 INFO - 'NO_EM_RESTART': '1', 10:22:31 INFO - 'PAGER': '/bin/cat', 10:22:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:31 INFO - 'PWD': '/builds/slave/test', 10:22:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:31 INFO - 'SHELL': '/bin/bash', 10:22:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVNaxBLO2m/Listeners', 10:22:31 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 10:22:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:31 INFO - 'USER': 'cltbld', 10:22:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:31 INFO - 'XPC_FLAGS': '0x0', 10:22:31 INFO - 'XPC_SERVICE_NAME': '0', 10:22:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:22:31 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:22:31 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:22:31 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:22:31 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:22:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:22:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:22:33 INFO - Installing collected packages: mozsystemmonitor 10:22:33 INFO - Running setup.py install for mozsystemmonitor 10:22:34 INFO - Successfully installed mozsystemmonitor 10:22:34 INFO - Cleaning up... 10:22:34 INFO - Return code: 0 10:22:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:22: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:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22: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:22:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a582c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a586768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc51488510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2d8d30>, '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 0x10a2a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c9be8>, '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.6jG53hyTO2/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.XVNaxBLO2m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:22:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:22:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:22:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jG53hyTO2/Render', 10:22:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:34 INFO - 'HOME': '/Users/cltbld', 10:22:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:34 INFO - 'LOGNAME': 'cltbld', 10:22:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:34 INFO - 'MOZ_NO_REMOTE': '1', 10:22:34 INFO - 'NO_EM_RESTART': '1', 10:22:34 INFO - 'PAGER': '/bin/cat', 10:22:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:34 INFO - 'PWD': '/builds/slave/test', 10:22:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:34 INFO - 'SHELL': '/bin/bash', 10:22:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVNaxBLO2m/Listeners', 10:22:34 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 10:22:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:34 INFO - 'USER': 'cltbld', 10:22:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:34 INFO - 'XPC_FLAGS': '0x0', 10:22:34 INFO - 'XPC_SERVICE_NAME': '0', 10:22:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:34 INFO - Downloading/unpacking jsonschema==2.5.1 10:22: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:22: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:22: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:22: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:22:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:22:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:22:36 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:22:36 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:22:36 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:22:36 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:22:36 INFO - Downloading functools32-3.2.3-2.tar.gz 10:22:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:22:36 INFO - warning: no files found matching '*.txt' 10:22:36 INFO - no previously-included directories found matching 'build' 10:22:36 INFO - no previously-included directories found matching 'dist' 10:22:36 INFO - no previously-included directories found matching '.git*' 10:22:36 INFO - Installing collected packages: jsonschema, functools32 10:22:36 INFO - Running setup.py install for functools32 10:22:36 INFO - warning: no files found matching '*.txt' 10:22:36 INFO - no previously-included directories found matching 'build' 10:22:36 INFO - no previously-included directories found matching 'dist' 10:22:36 INFO - no previously-included directories found matching '.git*' 10:22:36 INFO - Successfully installed jsonschema functools32 10:22:36 INFO - Cleaning up... 10:22:36 INFO - Return code: 0 10:22:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:22:36 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:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:36 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:22:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:36 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 0x10a582c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a586768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc51488510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2d8d30>, '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 0x10a2a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c9be8>, '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.6jG53hyTO2/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.XVNaxBLO2m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:22:36 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:22:36 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:22:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jG53hyTO2/Render', 10:22:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:36 INFO - 'HOME': '/Users/cltbld', 10:22:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:36 INFO - 'LOGNAME': 'cltbld', 10:22:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:36 INFO - 'MOZ_NO_REMOTE': '1', 10:22:36 INFO - 'NO_EM_RESTART': '1', 10:22:36 INFO - 'PAGER': '/bin/cat', 10:22:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:36 INFO - 'PWD': '/builds/slave/test', 10:22:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:36 INFO - 'SHELL': '/bin/bash', 10:22:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVNaxBLO2m/Listeners', 10:22:36 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 10:22:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:36 INFO - 'USER': 'cltbld', 10:22:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:36 INFO - 'XPC_FLAGS': '0x0', 10:22:36 INFO - 'XPC_SERVICE_NAME': '0', 10:22:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:22:36 INFO - Cleaning up... 10:22:37 INFO - Return code: 0 10:22:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:22: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:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22: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:22:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22: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', '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 0x10a582c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a586768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc51488510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2d8d30>, '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 0x10a2a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c9be8>, '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.6jG53hyTO2/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.XVNaxBLO2m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:22: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:22: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 blobuploader==1.2.4 10:22:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jG53hyTO2/Render', 10:22:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:37 INFO - 'HOME': '/Users/cltbld', 10:22:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:37 INFO - 'LOGNAME': 'cltbld', 10:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:37 INFO - 'MOZ_NO_REMOTE': '1', 10:22:37 INFO - 'NO_EM_RESTART': '1', 10:22:37 INFO - 'PAGER': '/bin/cat', 10:22:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:37 INFO - 'PWD': '/builds/slave/test', 10:22:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:37 INFO - 'SHELL': '/bin/bash', 10:22:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVNaxBLO2m/Listeners', 10:22:37 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 10:22:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:37 INFO - 'USER': 'cltbld', 10:22:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:37 INFO - 'XPC_FLAGS': '0x0', 10:22:37 INFO - 'XPC_SERVICE_NAME': '0', 10:22:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:37 INFO - Downloading/unpacking blobuploader==1.2.4 10:22: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:22: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:22: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:22: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:22:38 INFO - Downloading blobuploader-1.2.4.tar.gz 10:22:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:22:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:22:38 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:22:38 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:22:38 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:22:38 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:22:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:22:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:22: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:22: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:22: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:22: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:22:39 INFO - Downloading docopt-0.6.1.tar.gz 10:22:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:22:39 INFO - Installing collected packages: blobuploader, requests, docopt 10:22:39 INFO - Running setup.py install for blobuploader 10:22:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:22:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:22:39 INFO - Running setup.py install for requests 10:22:40 INFO - Running setup.py install for docopt 10:22:40 INFO - Successfully installed blobuploader requests docopt 10:22:40 INFO - Cleaning up... 10:22:40 INFO - Return code: 0 10:22:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:22:40 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:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:40 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:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:40 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 0x10a582c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a586768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc51488510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2d8d30>, '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 0x10a2a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c9be8>, '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.6jG53hyTO2/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.XVNaxBLO2m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:22:40 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:22:40 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:22:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jG53hyTO2/Render', 10:22:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:40 INFO - 'HOME': '/Users/cltbld', 10:22:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:40 INFO - 'LOGNAME': 'cltbld', 10:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:40 INFO - 'MOZ_NO_REMOTE': '1', 10:22:40 INFO - 'NO_EM_RESTART': '1', 10:22:40 INFO - 'PAGER': '/bin/cat', 10:22:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:40 INFO - 'PWD': '/builds/slave/test', 10:22:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:40 INFO - 'SHELL': '/bin/bash', 10:22:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVNaxBLO2m/Listeners', 10:22:40 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 10:22:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:40 INFO - 'USER': 'cltbld', 10:22:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:40 INFO - 'XPC_FLAGS': '0x0', 10:22:40 INFO - 'XPC_SERVICE_NAME': '0', 10:22:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:22:40 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-aWh8y7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:22:40 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-CUweHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:22:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Qgkr5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:22:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Ltuu0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:22:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-5w6MOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:22:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-qOTSO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:22:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-gy6aQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:22:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-W0WQuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:22:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-6o351s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:22:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-aKw7uX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:22:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-t4qKbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:22:41 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-XmhjUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:22:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-02ga7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:22:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-UV4Uhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:22:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-h4GF84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:22:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-8aBHYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:22:42 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-OXOxSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:22:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:22:42 INFO - Running setup.py install for manifestparser 10:22:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:22:42 INFO - Running setup.py install for mozcrash 10:22:42 INFO - Running setup.py install for mozdebug 10:22:43 INFO - Running setup.py install for mozdevice 10:22:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:22:43 INFO - Running setup.py install for mozfile 10:22:43 INFO - Running setup.py install for mozhttpd 10:22:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:22:43 INFO - Running setup.py install for mozinfo 10:22:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:22:43 INFO - Running setup.py install for mozInstall 10:22:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:22:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:22:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:22:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:22:43 INFO - Running setup.py install for mozleak 10:22:43 INFO - Running setup.py install for mozlog 10:22:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:22:44 INFO - Running setup.py install for moznetwork 10:22:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:22:44 INFO - Running setup.py install for mozprocess 10:22:44 INFO - Running setup.py install for mozprofile 10:22:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:22:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:22:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:22:44 INFO - Running setup.py install for mozrunner 10:22:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:22:44 INFO - Running setup.py install for mozscreenshot 10:22:44 INFO - Running setup.py install for moztest 10:22:45 INFO - Running setup.py install for mozversion 10:22:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:22:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:22:45 INFO - Cleaning up... 10:22:45 INFO - Return code: 0 10:22:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:22: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:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22: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:22:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:45 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 0x10a582c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a586768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc51488510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2d8d30>, '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 0x10a2a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c9be8>, '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.6jG53hyTO2/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.XVNaxBLO2m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:22:45 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:22:45 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:22:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jG53hyTO2/Render', 10:22:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:45 INFO - 'HOME': '/Users/cltbld', 10:22:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:45 INFO - 'LOGNAME': 'cltbld', 10:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:45 INFO - 'MOZ_NO_REMOTE': '1', 10:22:45 INFO - 'NO_EM_RESTART': '1', 10:22:45 INFO - 'PAGER': '/bin/cat', 10:22:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:45 INFO - 'PWD': '/builds/slave/test', 10:22:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:45 INFO - 'SHELL': '/bin/bash', 10:22:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVNaxBLO2m/Listeners', 10:22:45 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 10:22:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:45 INFO - 'USER': 'cltbld', 10:22:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:45 INFO - 'XPC_FLAGS': '0x0', 10:22:45 INFO - 'XPC_SERVICE_NAME': '0', 10:22:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:22:45 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-e5e3oI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:22:45 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:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:22:45 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-eN7VRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:22:45 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:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:22:45 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-m9NqvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:22:45 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:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:22:45 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-tMsSnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:22:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:22:45 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-aQ67fC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:22:46 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:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:22:46 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-1tBDyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:22:46 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:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:22:46 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-0mCxVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:22:46 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-wtEk7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:22:46 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-RxkNfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:22:46 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:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:22:46 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Rb06W1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:22:46 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:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:22:46 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-CUZ8in-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:22:46 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:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:22:46 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-188mO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:22:46 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:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:22:46 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-VdwEcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:22:46 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:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:22:46 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-nDlXz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:22:47 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:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:22:47 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-H08DcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:22:47 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:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:22:47 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-7VfoAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:22:47 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:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:22:47 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-19AlWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:22:47 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:22:47 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:22:47 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:22:47 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:22:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:22:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:22:47 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:22:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:22:47 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:22:47 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:22:47 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:22:47 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:22:48 INFO - Downloading blessings-1.6.tar.gz 10:22:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:22:48 INFO - Installing collected packages: blessings 10:22:48 INFO - Running setup.py install for blessings 10:22:48 INFO - Successfully installed blessings 10:22:48 INFO - Cleaning up... 10:22:49 INFO - Return code: 0 10:22:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:22:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:22:49 INFO - Reading from file tmpfile_stdout 10:22:49 INFO - Current package versions: 10:22:49 INFO - blessings == 1.6 10:22:49 INFO - blobuploader == 1.2.4 10:22:49 INFO - docopt == 0.6.1 10:22:49 INFO - functools32 == 3.2.3-2 10:22:49 INFO - jsonschema == 2.5.1 10:22:49 INFO - manifestparser == 1.1 10:22:49 INFO - mozInstall == 1.13 10:22:49 INFO - mozcrash == 1.0 10:22:49 INFO - mozdebug == 0.1 10:22:49 INFO - mozdevice == 0.51 10:22:49 INFO - mozfile == 1.2 10:22:49 INFO - mozhttpd == 0.7 10:22:49 INFO - mozinfo == 0.10 10:22:49 INFO - mozleak == 0.1 10:22:49 INFO - mozlog == 3.4 10:22:49 INFO - moznetwork == 0.27 10:22:49 INFO - mozprocess == 0.25 10:22:49 INFO - mozprofile == 0.28 10:22:49 INFO - mozrunner == 6.13 10:22:49 INFO - mozscreenshot == 0.1 10:22:49 INFO - mozsystemmonitor == 0.3 10:22:49 INFO - moztest == 0.8 10:22:49 INFO - mozversion == 1.4 10:22:49 INFO - psutil == 3.1.1 10:22:49 INFO - requests == 1.2.3 10:22:49 INFO - wsgiref == 0.1.2 10:22:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:49 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 0x10a582c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a586768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc51488510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2d8d30>, '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 0x10a2a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c9be8>, '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.6jG53hyTO2/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.XVNaxBLO2m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:22:49 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:22:49 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:22:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jG53hyTO2/Render', 10:22:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:49 INFO - 'HOME': '/Users/cltbld', 10:22:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:49 INFO - 'LOGNAME': 'cltbld', 10:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:49 INFO - 'MOZ_NO_REMOTE': '1', 10:22:49 INFO - 'NO_EM_RESTART': '1', 10:22:49 INFO - 'PAGER': '/bin/cat', 10:22:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:49 INFO - 'PWD': '/builds/slave/test', 10:22:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:49 INFO - 'SHELL': '/bin/bash', 10:22:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVNaxBLO2m/Listeners', 10:22:49 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 10:22:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:49 INFO - 'USER': 'cltbld', 10:22:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:49 INFO - 'XPC_FLAGS': '0x0', 10:22:49 INFO - 'XPC_SERVICE_NAME': '0', 10:22:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:49 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:22:49 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:22:49 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:22:49 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:22:49 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:22:49 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:22:49 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:22:49 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:22:49 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:22:49 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:22:49 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:22:49 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:22:49 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:22:49 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:22:50 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:22:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:22:51 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:22:51 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:22:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:22:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:22:51 INFO - Installing collected packages: marionette-driver 10:22:51 INFO - Running setup.py install for marionette-driver 10:22:51 INFO - Successfully installed marionette-driver 10:22:51 INFO - Cleaning up... 10:22:51 INFO - Return code: 0 10:22:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:22: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:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22: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:22:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:51 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 0x10a582c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a586768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc51488510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2d8d30>, '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 0x10a2a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c9be8>, '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.6jG53hyTO2/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.XVNaxBLO2m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:22:51 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:22:51 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:22:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jG53hyTO2/Render', 10:22:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:51 INFO - 'HOME': '/Users/cltbld', 10:22:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:51 INFO - 'LOGNAME': 'cltbld', 10:22:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:51 INFO - 'MOZ_NO_REMOTE': '1', 10:22:51 INFO - 'NO_EM_RESTART': '1', 10:22:51 INFO - 'PAGER': '/bin/cat', 10:22:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:51 INFO - 'PWD': '/builds/slave/test', 10:22:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:51 INFO - 'SHELL': '/bin/bash', 10:22:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVNaxBLO2m/Listeners', 10:22:51 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 10:22:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:51 INFO - 'USER': 'cltbld', 10:22:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:51 INFO - 'XPC_FLAGS': '0x0', 10:22:51 INFO - 'XPC_SERVICE_NAME': '0', 10:22:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:22:51 INFO - Cleaning up... 10:22:51 INFO - Return code: 0 10:22:51 INFO - Running post-action listener: _resource_record_post_action 10:22:51 INFO - Running post-action listener: _start_resource_monitoring 10:22:51 INFO - Starting resource monitoring. 10:22:51 INFO - [mozharness: 2017-06-13 17:22:51.633766Z] Finished create-virtualenv step (success) 10:22:51 INFO - [mozharness: 2017-06-13 17:22:51.634981Z] Running install step. 10:22:51 INFO - Running pre-action listener: _resource_record_pre_action 10:22:51 INFO - Running main action method: install 10:22:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:22:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:22:51 INFO - Reading from file tmpfile_stdout 10:22:51 INFO - Detecting whether we're running mozinstall >=1.0... 10:22:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:22:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:22:52 INFO - Reading from file tmpfile_stdout 10:22:52 INFO - Output received: 10:22:52 INFO - Usage: mozinstall [options] installer 10:22:52 INFO - Options: 10:22:52 INFO - -h, --help show this help message and exit 10:22:52 INFO - -d DEST, --destination=DEST 10:22:52 INFO - Directory to install application into. [default: 10:22:52 INFO - "/builds/slave/test"] 10:22:52 INFO - --app=APP Application being installed. [default: firefox] 10:22:52 INFO - mkdir: /builds/slave/test/build/application 10:22:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:22:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 10:23:07 INFO - Reading from file tmpfile_stdout 10:23:07 INFO - Output received: 10:23:07 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 10:23:07 INFO - Running post-action listener: _resource_record_post_action 10:23:07 INFO - [mozharness: 2017-06-13 17:23:07.753296Z] Finished install step (success) 10:23:07 INFO - [mozharness: 2017-06-13 17:23:07.753438Z] Running setup-mitmproxy step. 10:23:07 INFO - Running pre-action listener: _resource_record_pre_action 10:23:07 INFO - Running main action method: setup_mitmproxy 10:23:07 INFO - Skipping: mitmproxy is not required 10:23:07 INFO - Running post-action listener: _resource_record_post_action 10:23:07 INFO - [mozharness: 2017-06-13 17:23:07.754172Z] Finished setup-mitmproxy step (success) 10:23:07 INFO - [mozharness: 2017-06-13 17:23:07.754310Z] Running run-tests step. 10:23:07 INFO - Running pre-action listener: _resource_record_pre_action 10:23:07 INFO - Running main action method: run_tests 10:23:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:23:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:23:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:23:07 INFO - 2017-06-13 10:23:07.769 screenresolution[6621:119348] starting screenresolution argv=screenresolution get 10:23:07 INFO - 2017-06-13 10:23:07.772 screenresolution[6621:119348] Display 0: 1600x1200x32@60 10:23:07 INFO - 2017-06-13 10:23:07.778 screenresolution[6622:119350] starting screenresolution argv=screenresolution list 10:23:07 INFO - Available Modes on Display 0 10:23:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:23:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:23:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:23:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:23:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:23:08 INFO - Intel Iris: 10:23:08 INFO - Chipset Model: Intel Iris 10:23:08 INFO - Type: GPU 10:23:08 INFO - Bus: Built-In 10:23:08 INFO - VRAM (Dynamic, Max): 1536 MB 10:23:08 INFO - Vendor: Intel (0x8086) 10:23:08 INFO - Device ID: 0x0a2e 10:23:08 INFO - Revision ID: 0x0009 10:23:08 INFO - Displays: 10:23:08 INFO - Display: 10:23:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:23:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:23:08 INFO - Display Serial Number: 1600x1200 60 10:23:08 INFO - Main Display: Yes 10:23:08 INFO - Mirror: Off 10:23:08 INFO - Online: Yes 10:23:08 INFO - Rotation: Supported 10:23:08 INFO - Return code: 0 10:23:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:23:08 INFO - Python 2.7.3 10:23:08 INFO - Return code: 0 10:23:08 INFO - grabbing minidump binary from tooltool 10:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:08 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 0x10a2d8d30>, '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 0x10a2a2cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:23:08 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:23:08 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:23:08 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:23:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:23:08 INFO - Return code: 0 10:23:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:23:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:23:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:23:08 INFO - ENV: RUST_BACKTRACE is now 1 10:23:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:23:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:23:08 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/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:23:08 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/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0383 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:23:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6jG53hyTO2/Render', 10:23:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:08 INFO - 'HOME': '/Users/cltbld', 10:23:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:08 INFO - 'LOGNAME': 'cltbld', 10:23:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:23:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:08 INFO - 'MOZ_NO_REMOTE': '1', 10:23:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:23:08 INFO - 'NO_EM_RESTART': '1', 10:23:08 INFO - 'PAGER': '/bin/cat', 10:23:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:08 INFO - 'PWD': '/builds/slave/test', 10:23:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:23:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:08 INFO - 'RUST_BACKTRACE': '1', 10:23:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:23:08 INFO - 'SHELL': '/bin/bash', 10:23:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVNaxBLO2m/Listeners', 10:23:08 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 10:23:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:08 INFO - 'USER': 'cltbld', 10:23:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:08 INFO - 'XPC_FLAGS': '0x0', 10:23:08 INFO - 'XPC_SERVICE_NAME': '0', 10:23:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:08 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/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:23:08 INFO - mozversion application_buildid: 20170613090457 10:23:08 INFO - mozversion application_changeset: 02caa113457b2780c13bf0d7c26cb739558b5f5b 10:23:08 INFO - mozversion application_display_name: Firefox 10:23:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:23:08 INFO - mozversion application_name: Firefox 10:23:08 INFO - mozversion application_remotingname: firefox 10:23:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:23:08 INFO - mozversion application_vendor: Mozilla 10:23:08 INFO - mozversion application_version: 55.0 10:23:08 INFO - mozversion platform_buildid: 20170613090457 10:23:08 INFO - mozversion platform_changeset: 02caa113457b2780c13bf0d7c26cb739558b5f5b 10:23:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:23:08 INFO - mozversion platform_version: 55.0 10:23:08 INFO - using testdate: 1497374588 10:23:08 INFO - actual date: 1497374588 10:23:08 INFO - starting webserver on 'localhost:56894' 10:23:08 INFO - SUITE-START | Running 2 tests 10:23:08 INFO - TEST-START | dromaeo_css 10:23:08 INFO - operating with platform_type : mac_ 10:23:08 INFO - Initialising browser for dromaeo_css test... 10:23:08 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:56894/getInfo.html -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmppCAMLb/profile 10:23:08 INFO - TEST-INFO | started process 6632 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:56894/getInfo.html -foreground) 10:23:41 INFO - TEST-INFO | 6632: killed by SIGTERM 10:23:41 INFO - Browser initialized. 10:23:41 INFO - Running cycle 1/1 for dromaeo_css test... 10:23:41 INFO - TEST-INFO | started process 6639 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmppCAMLb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:23:43 INFO - PID 6639 | 2017-06-13 10:23:43.350 plugin-container[6640:119783] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 10:23:43 INFO - PID 6639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:23:43 INFO - PID 6639 | 2017-06-13 10:23:43.350 plugin-container[6640:119783] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a03, name = 'com.apple.CFPasteboardClient' 10:23:43 INFO - PID 6639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:23:43 INFO - PID 6639 | 2017-06-13 10:23:43.350 plugin-container[6640:119783] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:23:53 INFO - PID 6639 | RSS: Main: 184893440 10:23:53 INFO - PID 6639 | 10:26:55 INFO - PID 6639 | Cycle 1(1): loaded http://localhost:56894/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56894/tests/dromaeo/cssquery-ext.html) 10:26:57 INFO - PID 6639 | RSS: Main: 203988992 10:26:57 INFO - PID 6639 | 10:29:59 INFO - PID 6639 | Cycle 1(1): loaded http://localhost:56894/tests/dromaeo/cssquery-ext.html (next: http://localhost:56894/tests/dromaeo/cssquery-jquery.html) 10:29:59 INFO - PID 6639 | RSS: Main: 209567744 10:29:59 INFO - PID 6639 | 10:33:01 INFO - PID 6639 | Cycle 1(1): loaded http://localhost:56894/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56894/tests/dromaeo/cssquery-mootools.html) 10:33:01 INFO - PID 6639 | RSS: Main: 213753856 10:33:01 INFO - PID 6639 | 10:36:03 INFO - PID 6639 | Cycle 1(1): loaded http://localhost:56894/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56894/tests/dromaeo/cssquery-prototype.html) 10:36:04 INFO - PID 6639 | RSS: Main: 215375872 10:36:04 INFO - PID 6639 | 10:39:06 INFO - PID 6639 | Cycle 1(1): loaded http://localhost:56894/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56894/tests/dromaeo/cssquery-yui.html) 10:39:07 INFO - PID 6639 | RSS: Main: 215891968 10:39:07 INFO - PID 6639 | 10:42:09 INFO - PID 6639 | Cycle 1(1): loaded http://localhost:56894/tests/dromaeo/cssquery-yui.html (next: http://localhost:56894/tests/dromaeo/cssquery-dojo.html) 10:42:09 INFO - PID 6639 | RSS: Main: 214638592 10:42:09 INFO - PID 6639 | 10:42:09 INFO - PID 6639 | __start_tp_report 10:42:09 INFO - PID 6639 | _x_x_mozilla_page_load 10:42:09 INFO - PID 6639 | _x_x_mozilla_page_load_details 10:42:09 INFO - PID 6639 | |i|pagename|runs| 10:42:09 INFO - PID 6639 | |0;dojo.html;2997.3106185577317;3132.37352529494;3277.9344413111735;3678.4482327650867;3769.2649933263024;6338.80983570493;6358.14165087713;6371.235451745785;6376.713047912844;6388.936110638898;6447.935520644792;6481.449076828475;6490.643014634194;6495.155629768135;6544.541882068257;5666.405027472118;5716.456936591027;5732.369439361667;5854.18041474194;5871.70641467927;5166.819161329354;5770.134479828017;5845.853853653672;5850.122481627747;5877.206577112078;5053.797848086072;5245.86885327866;5711.485966263035;5752.856178595527;5766.077427611583;5638.4925356718095;5657.8019769307875;5718.971405142989;5746.971265143689;5813.593048486608;5673.23411339468;5674.3190817101795;5736.913946290809;5781.103928891029;5781.277340332458;5757.848430313912;5786.88426231473;6470.158879345702;6518.0548820421245;6536.11762412073;7078.433725301964;7120.359167674934;7152.105986751656;7159.463040272;7165.283471652793;6896.241413444517;6950.965245173792;6993.2307446181385;7085.929140708578;7212.567245965259;6552.312007239267;6676.766313179064;6686.5653732507235;6718.428933540655;6726.159230096238;7405.5926924019695;7407.407407407394;7417.629118544051;7427.888581671279;7442.21856705049;6706.664666766642;6852.965735171292;6987.685554150075;7000.92999070013;7018;7035.964820175969;7036.155661320674;7037.19072306681;7099.3965512930945;7226.963865180641;7017.9649101755185;7038.507304488637;7100.325469080429;7107.466939979522;7107.680154393065;7108.786736397917;7130.500864939509;7133.108361454818;7140.7500737474975;7143.178534468602;5683.54531637468;5686.914696279559;5694.572907031989;5702.429757024264;5721.799737009184;4886.364772579542;4925.1873440881855;5009.924851127236;5022.347094877642;5062.190049592047;6380.553361264667;6450.225972883284;6471.676416179266;6611.603303801788;6717.764878229238;7224.638768061681;7238.710451581983;7244.927550724531;7259.600721960237;7289.963550182215;6700.597964122168;6704.463642908556;6716.597004179765;6717.294684058202;6724.562903411361;6234.657093859791;6331;6340.619562826245;6355.936440635627;6360.586561873463;6457.903131452938;7168.139823221039;7347.963260183878;7452.598183242082;7480.8877866832045;6191.226096737908;7068.823279418056;7088.432925365855;7107.75122870697;7238.674259658223;7386.926130738624;7446.292602202782;7452.844809054605;7474.5888976106835;7475.850482990419;7121.359077683033;7134.250903655042;7147.892781608176;7150.5352152109945;7159.284071592799;7298.817529561803;7304.19653838088;7308.232635573188;7312.96343518279;7329.52358096722;6763.256041835296;6852.691628876612;7034.437245020488;7055.506114571931;7068.540544864567;5683.664338880442;5934.6142500737315;6784.6946887389195;6922.7923162305215;7033.226345101933;5923.911141332797;6791.252962174058;6853.691583878837;6873.725050997904;6900.102986611909;7025.68384422692;7029.929700703132;7031.296870312928;7032.226455090038;7048.083749112582;6620.63586502747;6683.832904177434;6691.899621505779;6788.185417749902;6869.278725733926 10:42:09 INFO - PID 6639 | |1;ext.html;815.9714409995652;859.7119964811787;872.6378552900545;887.9778005549863;891.670082069634;38819.80590097049;39611;39628.80185599075;39629.60370396295;39661;38826.223475530474;39075.21849563007;39108.60891391085;39217.803910980474;39602;6724;7702.152763196044;7702.152763196072;7793.883091753628;7829.49108307961;7725.652345644459;7843.450958432912;8359.498430094183;8595;8615.310775137974;8577.871331930026;8600.655973761073;8642.049374568793;8642.611082501271;8649.524276164808;8547.572621368907;8572.785680357978;8573.657053717843;8621.094785047542;8631.611577479029;8424.873626895602;8426.325893928532;8447.788805279855;8448.155184481564;8473.237408633191;36674.44988325151;36733;36901.63098369009;36922.63077369246;36957.81521092404;3655.378585640421;3683.3001729671278;3698.85204591816;3704.814759262053;3708.9072773180615;30192;30225.546616800766;30227;30237.546436803466;30258.54612180819;3218.5333126696733;3271.689189526991;3284.1625385526536;3289.4243507386113;3313.8840140595203;266920;271930.28069719445;272429;329719;332572;41200.58799412027;48327;48629.270560941615;48720.51279487231;48999.265011024865;50444.74777626162;50619;50673.74663126661;50677;50703;15937.36250549965;16019.599510012344;16027.358905643643;16047.358105675643;16115;13085;13153.802692959613;13203.669908252179;13230;13267.800982985264;13761.31193440324;13777.517786877612;13784.241866697228;13826.377812998438;13885.097468664504;5997.460228579362;6064.211652485149;6067.423594758491;6070.210872586624;6076.939230607637;921.8571121476182;930.0383956352248;973.7662960889477;1002.3033991376069;1010.6563768318659;22873.771262287497;23377.88311058434;23380.415489612897;23421;24023.399415014763;982.6609819612222;1083.864516935383;1149.494222542079;1181.285322379965;1186.7745128425572;12071.758564828655;12496.625101246977;12502.624921252376;12515.249085054926;12516.3741812909;313661;313871.86128138885;315353.42323288234;316334.41832790687;317018;54047.72976135173;54110.18834717482;54181.45818541764;54222.64443388868;54348.45651543513;1539.1534655939188;1618.6034421566792;1620.8622267107428;1621.7891674082293;1624.6669432766255;20351.898240508704;22779.88610056939;22791;23418.64872026887;23460;38226.617733822306;38409.807950960065;38438.423423648106;38467.80766096152;38824.417633736084;264681;265297;265764;265772;265837;30037.249068773457;30392.392152157274;30414.54378184373;30424.84787576048;34890.65109348874;35380.23098844996;36113;39631;41892;44468.332975004756;27661.58507622347;28980;29359.559606606344;29765.702342977158;30947.381052378376;29133.16072573524;29564.408711826072;30803.691963080084;30922.942425377794;31364.843175783975;34658.306833862676;36745.63254367422;36919.44620830743;36937.07657308589;36997.63002369942;23865.76070429731;29226.123216303546;29756.851215743784;30149.547756783228;31117.8444107778;18444.26222951067;22415;23713.881430592737;23764.881175594703;24135.39661508406 10:42:09 INFO - PID 6639 | |2;jquery.html;505.8027369325963;531.9069312562682;538.4185080113477;545.9099248623977;549.1076999875203;22067.558648827013;23887.28338149854;24105.192110591688;24154.87922560389;24162.27513174603;25354.619680704804;27906.581401278945;28131.437371252614;28200.576991345148;28208.576871346893;6563.868722625557;6664.100346453215;6680.966595167018;6728.865422691543;6739.797806065826;6687.629036047599;6708.698108585126;6714.127163468742;6716.026176204447;6726.697298621574;6122.448979591836;6478.902816457757;6627.204735431731;6653.401193892549;6688.83277918051;6636.270010298863;6637.303083176269;6650.5344625876205;6677.432418244431;6689.130413046273;5163.7676304566385;6126.6017708848785;6170.382961703838;6171.814845554641;6175.3515880832765;27063.45873082547;29151.271218219503;29522.26194345137;29597.70402295971;30664.386712265852;2340.157543284416;2371.1700904683394;2382.4401265702545;2400.5558971590312;2401.543706695722;16526;19689.507762305915;20244.79755202444;20245.29141480056;20279;2202.2512345802343;2227.287268074217;2255.233220704968;2257.390504563775;2266.9886650566805;200060;226223;228886;231460;237595;16115.516534503984;16291.429799956944;16538.173091345383;17008;17144.39994600183;21934;22054.228102016354;22180.667289990663;22255.666165007537;22261;10919.890801091888;10966.232363734622;10985.780284394268;11096.389698566494;11116.999470047735;9512.667056652985;10239.84640230397;10295.3822770634;10317.27779055474;10381.532831022621;9526.714198574055;9681.080297371738;9737.853932191023;9755.317127800985;9774.902250977399;2762.240383894445;2768.4601502707183;2778.347088434257;2779.4580056888713;2779.8332100074063;967.8112768010311;983.9811371204884;990.5839547390113;1039.5218199628255;1042.358949246208;19506;21705.565888682137;21950.890245548988;22527.774722252896;22863.08547658075;1005.1606908231709;1039.1998161415818;1048.758785479344;1072.292287090532;1075.956961721538;10171.338862973884;11149.609763658233;11401.600943967087;11411.771764564663;11736.295822250693;537333;541551;542064;557828;560591;143130;145910;147459.2627036858;148316;148487;1161.1289746337816;1161.89542941134;1208.0396085112357;1208.4441157067672;1216.294549161502;16878.409255675993;19391.933443660735;19480.415587532396;19645.803541964397;20051.897145657127;52085;62543;64537;67155.656886861;67933.66033169802;90586;90896.545517272;91149;92110.53944730233;92313.53843230741;12022.579209727617;12087.153899226969;12121.151519393557;12222.572209972608;12243.449044793184;13553.051286409856;18217.45347639573;18525;18611.627767444304;18886.811131889055;15356.462523811613;15422.383104676137;15434.382624695336;15640.530784076496;15690.372385104469;11661.125415593695;11831.349275789915;13518.93240533791;13912.930435347758;14284.285785710465;15835.683286334439;15927.761083583988;15980.360785568448;16202.918985404998;16203.75694364609;12032.398380081137;12081;12122;12141.514339426323;12166.817497737718;15032.6993460128;15206.923965380101;15290.923545382202;15340.616484587974;15520.689586208438 10:42:09 INFO - PID 6639 | |3;mootools.html;1528.7706843973403;1558.742807436773;1583.6753465539566;1592.2675569238143;1606.6947280016796;14281.500147494828;14784.039037462557;14829.55511334659;14859.925700371483;14882.851171488282;12286.63140105796;13797.103188292755;14553.708925821476;14604.977651564395;14634.414623415078;2902.5210840211394;2943.411317736461;2975.5536669499534;2977.865996030184;2978.3447641518833;2896.493535498912;2916.4167166566667;2923.4591600553968;2942.161483977067;2945.057581573897;2444.2545023767743;2919.970800292002;2927.3267148555738;2928.209383466463;2942.764578833699;2870.0815738963433;2898.2899189698446;2899.9855000724965;2902.2309088091674;2906.9127926162355;2854.9714502854913;2877.1943855720433;2879.6544414670166;2889.0321742216383;2895.3774938388347;14200.786988195185;14201.644958876008;14215.857841421557;14355.71288574233;14367.281635918162;7865.134835168127;8124.715634952749;8152;8164.265216130518;8173.346132309401;4284.871453856389;4785.64107691921;5037.974810125963;5141.974290128525;5150.51070148339;1427.3790900958013;1467.1270593996555;1482.8517148285193;1484.2949598940525;1490.8360080391149;21280.04239809197;21377.144914203414;21383.5723285535;21385;21480.785192148036;5794.449527294984;5922.970385148046;5988.401159884064;6436.8390790230615;6908.938514039342;14840.703185936221;14842.777358339634;14857.851421485862;14877.702445951021;14919.552413427615;7695.653695583712;7785.3771698264;7852.489588176751;7865.606719664108;7874.842503149905;5990.940090599038;6204.875902481925;6350.301466838644;6832.897506537406;7345.669444874993;5254.632175747661;5503.284573005483;5508.504234618824;5581.5813813963305;5605.383407825136;1594.840515948396;1614.2413065859027;1615.3780794394243;1629.0470075006017;1640.425850952181;800.1358532784578;838.828040251747;842.1031601344671;845.8139209373928;864.6584599083327;5744.339400968858;6985.441164706811;7190.280971902873;7343.963280183565;7477.588732619649;622.2843729710778;672.8351553869334;705.5766540076016;722.8903632923437;739.7189068154067;4251.341042138473;4863.027394521039;5301.289097631673;5330.200469929464;5480.945190548123;18997;19076.236950521823;19164.520886977938;19262;19380.8061919379;18572.25710971573;18804.811951880307;18834.15246313919;19182.136803843583;19382.12780424884;9939.503024848873;10073.949630251802;10153.340032897986;10160.13638840692;10217.33587316822;6705.262421133769;8425.536595487061;8447.366447516339;8472.49165050099;8762.430442021248;17279.65440691154;17399.913000434917;17535.912320438318;17677.558061048578;17841.643167136845;16277.511674649779;16463.60059394965;16766;17737;17770.91114544419;9504.857427138737;10288.691339259834;10601.628942986958;11294.04000659953;11521.942390287995;12116.575919842764;12237.449314780679;12945.223286602833;13467.191968481922;13704.451821927012;10896.836547451636;11097.778044439228;11127.443627818415;11353.205275630626;11584.189106762446;10564.366138031743;10723.356598604109;11113.611023614134;11181.440927953407;11199.160062995144;9432.292578056811;9475.24198064167;9489.525523723923;9493.193078588121;9541.666041688508;6297.937020629918;6857.862842743018;6866.759663411956;7102.8635418332815;7182.60495672743;6929.3070692930305;7131.607761573163;7326.4138868889295;7362.190159082389;7390.187079421152 10:42:09 INFO - PID 6639 | |4;prototype.html;464.7002683269291;484.60020483101437;492.9038837426701;503.647446787249;505.801251035047;5486.588505862056;5596.300462442195;6723.731050757976;6738.292479289666;6780.660966951657;6316.810495685137;6552.770653027145;6553.606783592976;6570.244421891484;6582.572132811376;4941.827036053739;4960.826371077004;4995.925061124086;4999.32509111269;5003.674761140514;4980.900381992358;4990.3762029746285;4994.275830004647;4999.8000079996755;5014.5737612303;4292.399064131024;4647.838040489877;4953.306537084811;4954.455009948902;4955.578775804043;3820.7134464915243;4792.113459010059;4820.831270905536;4843.346148269974;4915.778789954461;3749.268892565951;4261.509926358446;4358.36803663467;4386.188555117314;4389.978050109761;6379.266384365811;6395.648239346834;6405.711742971577;6450.8064758057335;6452.580582262138;5882.911661342665;5884.7940322088525;5886.23478947734;5898.970505147489;5969.611975221596;5862.384449632812;7016.894746578805;7086.751963681247;7091.432685385208;7099.503034787567;4955.182394904837;4961.801527938878;4985.451600323961;4988.501149885019;5015.1975683890405;7084.220735719066;7423.962880185619;7428.291492956596;7499.175090740015;7500.662470188855;4333.58830911063;5063.088644044034;5089.982003599295;5091.847244582668;5110.591152707774;5160.48395160481;5195.428502864757;5207.140821764367;5213.348331458567;5213.6089793265655;5859.882802343929;5863.149843272701;5880.764769409261;5896.233489646404;5898.380670029671;7092.680829362588;7101.857962840714;7115.573065615976;7118.537295075907;7195.748148814766;1633.289519059213;1642.8603568696556;1696.642685851314;1764.4177421451147;1767.1959258537336;1176.764397382762;1188.3701638131804;1201.0031673710798;1214.8481439820023;1218.165556593736;6557.278699343068;6721.529492935448;6726.0158253315385;6749.460043196533;6838.897416538756;6665.900011499832;7086.574805511686;7393.55638661682;7435.814104647319;7453.291937265951;6715.563488373338;6717.193936727624;6727.899081513781;6738.494612904052;6776.186857577122;3407.84133394647;3619.3304238715396;3832.1186127190344;3852.4221366795205;3862.4978752761963;5801.3038435388025;6544.018397240452;7327.303906128909;7604.8479030420185;7634.923650763421;6872.587644741331;7456.078987867981;7533.8116547085665;7551.622418879034;7584.028152680343;6526.4452521536205;6647.86704265912;6978.232394436608;7497.325240728252;7609.467337286558;6701.631410272482;7269.382102521345;7283.344498995116;7359.963200183965;7386.2613738625705;7168.569885806868;7173.569585824867;7209.423246140192;7225.602591857499;7263.636818158965;6652.634105124071;6922.550034247758;7136.250693677297;7634.4655874088285;7658.387329013777;5036.169032109661;5051.065552872583;5058.367704036996;5072.771725272298;5090.7709153088945;7393.593352365671;7396.445266604918;7399.852002959803;7433.70265189408;7505.924940750522;5423.023855705853;7072.186698529735;7103.786886393416;7115.181754098138;7127.679254433459;6178.691065446799;6567.194021644086;7024.227334993045;7050.9138315992095;7082.716691332289;5398.352197736297;6425.967870160619;6878.896816547655;6905.516613836983;6960.686769095504;6351.428371446592;6773.661316934232;7005.478027909847;7080.539764915264;7154.749583764751;5457.399686034453;5937.970310148422;6286.591371560834;6908.620025898624;6936.0636314098965 10:42:09 INFO - PID 6639 | |5;yui.html;829.8055019281065;883.6112110671305;890.7728529225046;897.5773399162327;906.9727908162755;15489;16247.106409147522;16780.496585102435;17729.379471718476;18769.06154692265;14786.334614942327;14819.259037048132;14835.851641483581;14859.628509287273;16958.40645577405;881.2289246908955;1017.8473229015651;1046.1317106801337;1049.4805071489623;1053.6048981631889;1050.6637875879721;1052.8894466081028;1055.8521806946994;1056.2183983851933;1057.619257067455;1081.3944191252924;1081.6376513867863;1082.1775450657517;1084.4848696868964;1084.8264277715566;995.3381001633904;1085.7285678580356;1098.4452851310118;1104.9779004419947;1109.1127098321392;799.9200079992013;809.1221025187679;817.3502065857598;821.6713314674118;822.3174764945081;18474.538136546693;18765.967871767047;18785.15466803997;18982.905085474624;18990.955497447634;18386.63226735471;18519.907400463046;18643.34748283817;18825.152868120967;19019.61960760791;15466;15486.5354039379;15487.303071361815;15548.844511554853;15643.452479163288;3685.870994515206;3793.6775374093236;3795.905102372435;3804.6385593368586;3831.3678243089857;155510;160992.19503902405;163696.18151909165;164582;168705;14171.362288696828;14267.78598321026;14405.92797036008;14439.639009024648;14548.854511454962;26903.192904212905;28705.28236794097;28847.990320338686;29039.41921161565;29098.127056188347;8120.512769233865;8158.3065439438315;8176.46852954556;8210.917890821134;8213.79465513367;4058.3303754880703;4118.897027574335;4163.875083747493;4167.8958026049595;4168.291390463629;4064.4716186896108;4619.145458090264;4965.851024469272;4986.0277245936695;4994.825181118644;1011.5397494140149;1017.8524114003576;1019.1642852860583;1028.8508166315992;1035.9689209323733;655.8884989551788;668.5554106519111;669.0800149794034;677.5054210426416;704.2394214248599;10398;12150.939245303716;12234.694132646755;12412.441440135215;12538.498460061495;679.5141473846223;686.5400181878153;689.8482333886537;695.2387136085978;712.9786106416713;5401.810936617199;5669.574781891375;5770.596058275881;6007.669578173242;6034.1853849730605;130625;132903;135478;141060;142578;110266;111335.20653554173;112133;118630.22054669187;126031.73968260383;85025.87435314043;100113;100836;108476.37285440725;108885;11449.885501144881;11754.529818807152;12635.557755478514;13227.3386330685;13267.469301227842;45972.770136149105;47417.76291118522;47532.7623361881;48531.5146848527;50143.498565013884;63611;64489.35510644833;67464.66267668827;68077.65961170361;68928.31071689219;17409.651806963535;17986.100694965462;18009.549761256072;18209.271629134688;18399.908000460448;19009;19027.619447611247;19202;19215.943123128363;19513.024348782787;15746;17202.65594688124;18027.909860450614;18094.728579071587;18622.81377186265;15587.142707151215;15784.842151578338;17347.913260433616;18731.90634046821;19086.904565477085;14648.487302944772;17546.21042053085;18197.818021819614;18404.815951840847;18410.987395693366;11956;12040.157188996685;12465.688357791129;12836.358182091044;13212.669683257995;13910.721785564434;14274.357653905392;14442.53379189008;14507.92746036263;14905.403783848524 10:42:09 INFO - PID 6639 | __end_tp_report 10:42:09 INFO - PID 6639 | __start_cc_report 10:42:09 INFO - PID 6639 | _x_x_mozilla_cycle_collect,394 10:42:09 INFO - PID 6639 | __end_cc_report 10:42:09 INFO - PID 6639 | __startTimestamp1497375729490__endTimestamp 10:42:09 INFO - PID 6639 | 10:42:09 INFO - PID 6639 | ------- Summary: start ------- 10:42:09 INFO - PID 6639 | Number of tests: 6 10:42:09 INFO - PID 6639 | 10:42:09 INFO - PID 6639 | [#0] dojo.html Cycles:165 Average:6506.07 Median:6724.56 stddev:856.57 (12.7%) stddev-sans-first:813.77 10:42:09 INFO - PID 6639 | Values: 2997.3 3132.4 3277.9 3678.4 3769.3 6338.8 6358.1 6371.2 6376.7 6388.9 6447.9 6481.4 6490.6 6495.2 6544.5 5666.4 5716.5 5732.4 5854.2 5871.7 5166.8 5770.1 5845.9 5850.1 5877.2 5053.8 5245.9 5711.5 5752.9 5766.1 5638.5 5657.8 5719.0 5747.0 5813.6 5673.2 5674.3 5736.9 5781.1 5781.3 5757.8 5786.9 6470.2 6518.1 6536.1 7078.4 7120.4 7152.1 7159.5 7165.3 6896.2 6951.0 6993.2 7085.9 7212.6 6552.3 6676.8 6686.6 6718.4 6726.2 7405.6 7407.4 7417.6 7427.9 7442.2 6706.7 6853.0 6987.7 7000.9 7018.0 7036.0 7036.2 7037.2 7099.4 7227.0 7018.0 7038.5 7100.3 7107.5 7107.7 7108.8 7130.5 7133.1 7140.8 7143.2 5683.5 5686.9 5694.6 5702.4 5721.8 4886.4 4925.2 5009.9 5022.3 5062.2 6380.6 6450.2 6471.7 6611.6 6717.8 7224.6 7238.7 7244.9 7259.6 7290.0 6700.6 6704.5 6716.6 6717.3 6724.6 6234.7 6331.0 6340.6 6355.9 6360.6 6457.9 7168.1 7348.0 7452.6 7480.9 6191.2 7068.8 7088.4 7107.8 7238.7 7386.9 7446.3 7452.8 7474.6 7475.9 7121.4 7134.3 7147.9 7150.5 7159.3 7298.8 7304.2 7308.2 7313.0 7329.5 6763.3 6852.7 7034.4 7055.5 7068.5 5683.7 5934.6 6784.7 6922.8 7033.2 5923.9 6791.3 6853.7 6873.7 6900.1 7025.7 7029.9 7031.3 7032.2 7048.1 6620.6 6683.8 6691.9 6788.2 6869.3 10:42:09 INFO - PID 6639 | 10:42:09 INFO - PID 6639 | [#1] ext.html Cycles:180 Average:44172.72 Median:34774.48 stddev:76806.51 (220.9%) stddev-sans-first:76952.98 10:42:09 INFO - PID 6639 | Values: 816.0 859.7 872.6 888.0 891.7 38819.8 39611.0 39628.8 39629.6 39661.0 38826.2 39075.2 39108.6 39217.8 39602.0 6724.0 7702.2 7702.2 7793.9 7829.5 7725.7 7843.5 8359.5 8595.0 8615.3 8577.9 8600.7 8642.0 8642.6 8649.5 8547.6 8572.8 8573.7 8621.1 8631.6 8424.9 8426.3 8447.8 8448.2 8473.2 36674.4 36733.0 36901.6 36922.6 36957.8 3655.4 3683.3 3698.9 3704.8 3708.9 30192.0 30225.5 30227.0 30237.5 30258.5 3218.5 3271.7 3284.2 3289.4 3313.9 266920.0 271930.3 272429.0 329719.0 332572.0 41200.6 48327.0 48629.3 48720.5 48999.3 50444.7 50619.0 50673.7 50677.0 50703.0 15937.4 16019.6 16027.4 16047.4 16115.0 13085.0 13153.8 13203.7 13230.0 13267.8 13761.3 13777.5 13784.2 13826.4 13885.1 5997.5 6064.2 6067.4 6070.2 6076.9 921.9 930.0 973.8 1002.3 1010.7 22873.8 23377.9 23380.4 23421.0 24023.4 982.7 1083.9 1149.5 1181.3 1186.8 12071.8 12496.6 12502.6 12515.2 12516.4 313661.0 313871.9 315353.4 316334.4 317018.0 54047.7 54110.2 54181.5 54222.6 54348.5 1539.2 1618.6 1620.9 1621.8 1624.7 20351.9 22779.9 22791.0 23418.6 23460.0 38226.6 38409.8 38438.4 38467.8 38824.4 264681.0 265297.0 265764.0 265772.0 265837.0 30037.2 30392.4 30414.5 30424.8 34890.7 35380.2 36113.0 39631.0 41892.0 44468.3 27661.6 28980.0 29359.6 29765.7 30947.4 29133.2 29564.4 30803.7 30922.9 31364.8 34658.3 36745.6 36919.4 36937.1 36997.6 23865.8 29226.1 29756.9 30149.5 31117.8 18444.3 22415.0 23713.9 23764.9 24135.4 10:42:09 INFO - PID 6639 | 10:42:09 INFO - PID 6639 | [#2] jquery.html Cycles:180 Average:40011.81 Median:22258.33 stddev:96523.61 (433.7%) stddev-sans-first:96748.81 10:42:09 INFO - PID 6639 | Values: 505.8 531.9 538.4 545.9 549.1 22067.6 23887.3 24105.2 24154.9 24162.3 25354.6 27906.6 28131.4 28200.6 28208.6 6563.9 6664.1 6681.0 6728.9 6739.8 6687.6 6708.7 6714.1 6716.0 6726.7 6122.4 6478.9 6627.2 6653.4 6688.8 6636.3 6637.3 6650.5 6677.4 6689.1 5163.8 6126.6 6170.4 6171.8 6175.4 27063.5 29151.3 29522.3 29597.7 30664.4 2340.2 2371.2 2382.4 2400.6 2401.5 16526.0 19689.5 20244.8 20245.3 20279.0 2202.3 2227.3 2255.2 2257.4 2267.0 200060.0 226223.0 228886.0 231460.0 237595.0 16115.5 16291.4 16538.2 17008.0 17144.4 21934.0 22054.2 22180.7 22255.7 22261.0 10919.9 10966.2 10985.8 11096.4 11117.0 9512.7 10239.8 10295.4 10317.3 10381.5 9526.7 9681.1 9737.9 9755.3 9774.9 2762.2 2768.5 2778.3 2779.5 2779.8 967.8 984.0 990.6 1039.5 1042.4 19506.0 21705.6 21950.9 22527.8 22863.1 1005.2 1039.2 1048.8 1072.3 1076.0 10171.3 11149.6 11401.6 11411.8 11736.3 537333.0 541551.0 542064.0 557828.0 560591.0 143130.0 145910.0 147459.3 148316.0 148487.0 1161.1 1161.9 1208.0 1208.4 1216.3 16878.4 19391.9 19480.4 19645.8 20051.9 52085.0 62543.0 64537.0 67155.7 67933.7 90586.0 90896.5 91149.0 92110.5 92313.5 12022.6 12087.2 12121.2 12222.6 12243.4 13553.1 18217.5 18525.0 18611.6 18886.8 15356.5 15422.4 15434.4 15640.5 15690.4 11661.1 11831.3 13518.9 13912.9 14284.3 15835.7 15927.8 15980.4 16202.9 16203.8 12032.4 12081.0 12122.0 12141.5 12166.8 15032.7 15206.9 15290.9 15340.6 15520.7 10:42:09 INFO - PID 6639 | 10:42:09 INFO - PID 6639 | [#3] mootools.html Cycles:180 Average:8643.49 Median:2897.39 stddev:5795.23 (200.0%) stddev-sans-first:5786.83 10:42:09 INFO - PID 6639 | Values: 1528.8 1558.7 1583.7 1592.3 1606.7 14281.5 14784.0 14829.6 14859.9 14882.9 12286.6 13797.1 14553.7 14605.0 14634.4 2902.5 2943.4 2975.6 2977.9 2978.3 2896.5 2916.4 2923.5 2942.2 2945.1 2444.3 2920.0 2927.3 2928.2 2942.8 2870.1 2898.3 2900.0 2902.2 2906.9 2855.0 2877.2 2879.7 2889.0 2895.4 14200.8 14201.6 14215.9 14355.7 14367.3 7865.1 8124.7 8152.0 8164.3 8173.3 4284.9 4785.6 5038.0 5142.0 5150.5 1427.4 1467.1 1482.9 1484.3 1490.8 21280.0 21377.1 21383.6 21385.0 21480.8 5794.4 5923.0 5988.4 6436.8 6908.9 14840.7 14842.8 14857.9 14877.7 14919.6 7695.7 7785.4 7852.5 7865.6 7874.8 5990.9 6204.9 6350.3 6832.9 7345.7 5254.6 5503.3 5508.5 5581.6 5605.4 1594.8 1614.2 1615.4 1629.0 1640.4 800.1 838.8 842.1 845.8 864.7 5744.3 6985.4 7190.3 7344.0 7477.6 622.3 672.8 705.6 722.9 739.7 4251.3 4863.0 5301.3 5330.2 5480.9 18997.0 19076.2 19164.5 19262.0 19380.8 18572.3 18804.8 18834.2 19182.1 19382.1 9939.5 10073.9 10153.3 10160.1 10217.3 6705.3 8425.5 8447.4 8472.5 8762.4 17279.7 17399.9 17535.9 17677.6 17841.6 16277.5 16463.6 16766.0 17737.0 17770.9 9504.9 10288.7 10601.6 11294.0 11521.9 12116.6 12237.4 12945.2 13467.2 13704.5 10896.8 11097.8 11127.4 11353.2 11584.2 10564.4 10723.4 11113.6 11181.4 11199.2 9432.3 9475.2 9489.5 9493.2 9541.7 6297.9 6857.9 6866.8 7102.9 7182.6 6929.3 7131.6 7326.4 7362.2 7390.2 10:42:09 INFO - PID 6639 | 10:42:09 INFO - PID 6639 | [#4] prototype.html Cycles:180 Average:5766.26 Median:6438.39 stddev:1757.07 (27.3%) stddev-sans-first:1716.35 10:42:09 INFO - PID 6639 | Values: 464.7 484.6 492.9 503.6 505.8 5486.6 5596.3 6723.7 6738.3 6780.7 6316.8 6552.8 6553.6 6570.2 6582.6 4941.8 4960.8 4995.9 4999.3 5003.7 4980.9 4990.4 4994.3 4999.8 5014.6 4292.4 4647.8 4953.3 4954.5 4955.6 3820.7 4792.1 4820.8 4843.3 4915.8 3749.3 4261.5 4358.4 4386.2 4390.0 6379.3 6395.6 6405.7 6450.8 6452.6 5882.9 5884.8 5886.2 5899.0 5969.6 5862.4 7016.9 7086.8 7091.4 7099.5 4955.2 4961.8 4985.5 4988.5 5015.2 7084.2 7424.0 7428.3 7499.2 7500.7 4333.6 5063.1 5090.0 5091.8 5110.6 5160.5 5195.4 5207.1 5213.3 5213.6 5859.9 5863.1 5880.8 5896.2 5898.4 7092.7 7101.9 7115.6 7118.5 7195.7 1633.3 1642.9 1696.6 1764.4 1767.2 1176.8 1188.4 1201.0 1214.8 1218.2 6557.3 6721.5 6726.0 6749.5 6838.9 6665.9 7086.6 7393.6 7435.8 7453.3 6715.6 6717.2 6727.9 6738.5 6776.2 3407.8 3619.3 3832.1 3852.4 3862.5 5801.3 6544.0 7327.3 7604.8 7634.9 6872.6 7456.1 7533.8 7551.6 7584.0 6526.4 6647.9 6978.2 7497.3 7609.5 6701.6 7269.4 7283.3 7360.0 7386.3 7168.6 7173.6 7209.4 7225.6 7263.6 6652.6 6922.6 7136.3 7634.5 7658.4 5036.2 5051.1 5058.4 5072.8 5090.8 7393.6 7396.4 7399.9 7433.7 7505.9 5423.0 7072.2 7103.8 7115.2 7127.7 6178.7 6567.2 7024.2 7050.9 7082.7 5398.4 6426.0 6878.9 6905.5 6960.7 6351.4 6773.7 7005.5 7080.5 7154.7 5457.4 5938.0 6286.6 6908.6 6936.1 10:42:09 INFO - PID 6639 | 10:42:09 INFO - PID 6639 | [#5] yui.html Cycles:180 Average:25940.82 Median:18018.73 stddev:39621.16 (219.9%) stddev-sans-first:39687.44 10:42:09 INFO - PID 6639 | Values: 829.8 883.6 890.8 897.6 907.0 15489.0 16247.1 16780.5 17729.4 18769.1 14786.3 14819.3 14835.9 14859.6 16958.4 881.2 1017.8 1046.1 1049.5 1053.6 1050.7 1052.9 1055.9 1056.2 1057.6 1081.4 1081.6 1082.2 1084.5 1084.8 995.3 1085.7 1098.4 1105.0 1109.1 799.9 809.1 817.4 821.7 822.3 18474.5 18766.0 18785.2 18982.9 18991.0 18386.6 18519.9 18643.3 18825.2 19019.6 15466.0 15486.5 15487.3 15548.8 15643.5 3685.9 3793.7 3795.9 3804.6 3831.4 155510.0 160992.2 163696.2 164582.0 168705.0 14171.4 14267.8 14405.9 14439.6 14548.9 26903.2 28705.3 28848.0 29039.4 29098.1 8120.5 8158.3 8176.5 8210.9 8213.8 4058.3 4118.9 4163.9 4167.9 4168.3 4064.5 4619.1 4965.9 4986.0 4994.8 1011.5 1017.9 1019.2 1028.9 1036.0 655.9 668.6 669.1 677.5 704.2 10398.0 12150.9 12234.7 12412.4 12538.5 679.5 686.5 689.8 695.2 713.0 5401.8 5669.6 5770.6 6007.7 6034.2 130625.0 132903.0 135478.0 141060.0 142578.0 110266.0 111335.2 112133.0 118630.2 126031.7 85025.9 100113.0 100836.0 108476.4 108885.0 11449.9 11754.5 12635.6 13227.3 13267.5 45972.8 47417.8 47532.8 48531.5 50143.5 63611.0 64489.4 67464.7 68077.7 68928.3 17409.7 17986.1 18009.5 18209.3 18399.9 19009.0 19027.6 19202.0 19215.9 19513.0 15746.0 17202.7 18027.9 18094.7 18622.8 15587.1 15784.8 17347.9 18731.9 19086.9 14648.5 17546.2 18197.8 18404.8 18411.0 11956.0 12040.2 12465.7 12836.4 13212.7 13910.7 14274.4 14442.5 14507.9 14905.4 10:42:09 INFO - PID 6639 | -------- Summary: end -------- 10:42:09 INFO - PID 6639 | 10:42:10 INFO - TEST-INFO | 6639: exit 0 10:42:10 INFO - TEST-OK | dromaeo_css | took 1141629ms 10:42:10 INFO - TEST-START | kraken 10:42:10 INFO - operating with platform_type : mac_ 10:42:10 INFO - Initialising browser for kraken test... 10:42:10 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:56894/getInfo.html -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpgyMof9/profile 10:42:10 INFO - TEST-INFO | started process 6647 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:56894/getInfo.html -foreground) 10:42:43 INFO - TEST-INFO | 6647: killed by SIGTERM 10:42:43 INFO - Browser initialized. 10:42:43 INFO - Running cycle 1/1 for kraken test... 10:42:43 INFO - TEST-INFO | started process 6650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpgyMof9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:42:44 INFO - PID 6650 | 2017-06-13 10:42:44.757 plugin-container[6651:122208] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9343, name = 'com.apple.tsm.portname' 10:42:44 INFO - PID 6650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:42:44 INFO - PID 6650 | 2017-06-13 10:42:44.758 plugin-container[6651:122208] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4807, name = 'com.apple.CFPasteboardClient' 10:42:44 INFO - PID 6650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:42:44 INFO - PID 6650 | 2017-06-13 10:42:44.758 plugin-container[6651:122208] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:42:54 INFO - PID 6650 | RSS: Main: 184684544 10:42:54 INFO - PID 6650 | 10:42:57 INFO - PID 6650 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56894/tests/kraken/driver.html?testName=audio-beat-detection) 10:42:58 INFO - PID 6650 | RSS: Main: 198057984 10:42:58 INFO - PID 6650 | 10:43:01 INFO - PID 6650 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56894/tests/kraken/driver.html?testName=audio-dft) 10:43:01 INFO - PID 6650 | RSS: Main: 207179776 10:43:01 INFO - PID 6650 | 10:43:04 INFO - PID 6650 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56894/tests/kraken/driver.html?testName=audio-fft) 10:43:05 INFO - PID 6650 | RSS: Main: 211865600 10:43:05 INFO - PID 6650 | 10:43:07 INFO - PID 6650 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56894/tests/kraken/driver.html?testName=audio-oscillator) 10:43:08 INFO - PID 6650 | RSS: Main: 212828160 10:43:08 INFO - PID 6650 | 10:43:10 INFO - PID 6650 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56894/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:43:10 INFO - PID 6650 | RSS: Main: 213344256 10:43:10 INFO - PID 6650 | 10:43:13 INFO - PID 6650 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56894/tests/kraken/driver.html?testName=imaging-darkroom) 10:43:14 INFO - PID 6650 | RSS: Main: 217071616 10:43:14 INFO - PID 6650 | 10:43:17 INFO - PID 6650 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56894/tests/kraken/driver.html?testName=imaging-desaturate) 10:43:17 INFO - PID 6650 | RSS: Main: 217169920 10:43:17 INFO - PID 6650 | 10:43:20 INFO - PID 6650 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56894/tests/kraken/driver.html?testName=json-parse-financial) 10:43:21 INFO - PID 6650 | RSS: Main: 218636288 10:43:21 INFO - PID 6650 | 10:43:23 INFO - PID 6650 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56894/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:43:23 INFO - PID 6650 | RSS: Main: 217939968 10:43:23 INFO - PID 6650 | 10:43:25 INFO - PID 6650 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:43:26 INFO - PID 6650 | RSS: Main: 217944064 10:43:26 INFO - PID 6650 | 10:43:28 INFO - PID 6650 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:43:28 INFO - PID 6650 | RSS: Main: 217964544 10:43:28 INFO - PID 6650 | 10:43:31 INFO - PID 6650 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:43:32 INFO - PID 6650 | RSS: Main: 217989120 10:43:32 INFO - PID 6650 | 10:43:35 INFO - PID 6650 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:43:35 INFO - PID 6650 | RSS: Main: 218288128 10:43:35 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | Cycle 1(1): loaded http://localhost:56894/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56894/tests/kraken/driver.html?testName=ai-astar) 10:43:37 INFO - PID 6650 | RSS: Main: 219750400 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | __start_tp_report 10:43:37 INFO - PID 6650 | _x_x_mozilla_page_load 10:43:37 INFO - PID 6650 | _x_x_mozilla_page_load_details 10:43:37 INFO - PID 6650 | |i|pagename|runs| 10:43:37 INFO - PID 6650 | |0;ai-astar;101;114;106;121;118;115;115;103;114;113 10:43:37 INFO - PID 6650 | |1;audio-beat-detection;158;144;152;169;142;158;127;106;154;108 10:43:37 INFO - PID 6650 | |2;audio-dft;198;201;197;201;202;195;202;198;203;200 10:43:37 INFO - PID 6650 | |3;audio-fft;70;84;74;157;57;56;62;59;125;62 10:43:37 INFO - PID 6650 | |4;audio-oscillator;69;76;131;65;54;75;141;100;104;151 10:43:37 INFO - PID 6650 | |5;imaging-gaussian-blur;90;91;89;90;91;131;91;90;90;103 10:43:37 INFO - PID 6650 | |6;imaging-darkroom;94;96;101;140;152;95;96;97;96;97 10:43:37 INFO - PID 6650 | |7;imaging-desaturate;93;91;103;149;130;92;92;91;94;89 10:43:37 INFO - PID 6650 | |8;json-parse-financial;49;49;51;53;52;52;52;52;52;52 10:43:37 INFO - PID 6650 | |9;json-stringify-tinderbox;53;55;54;63;75;88;84;60;55;56 10:43:37 INFO - PID 6650 | |10;stanford-crypto-aes;80;79;76;79;78;78;80;77;94;99 10:43:37 INFO - PID 6650 | |11;stanford-crypto-ccm;149;148;126;150;144;144;146;138;133;157 10:43:37 INFO - PID 6650 | |12;stanford-crypto-pbkdf2;175;180;174;174;180;219;173;175;172;173 10:43:37 INFO - PID 6650 | |13;stanford-crypto-sha256-iterative;64;64;66;63;63;73;83;95;98;93 10:43:37 INFO - PID 6650 | __end_tp_report 10:43:37 INFO - PID 6650 | __start_cc_report 10:43:37 INFO - PID 6650 | _x_x_mozilla_cycle_collect,981 10:43:37 INFO - PID 6650 | __end_cc_report 10:43:37 INFO - PID 6650 | __startTimestamp1497375817910__endTimestamp 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | ------- Summary: start ------- 10:43:37 INFO - PID 6650 | Number of tests: 14 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | [#0] ai-astar Cycles:10 Average:112.00 Median:114.50 stddev:6.51 (5.7%) stddev-sans-first:5.56 10:43:37 INFO - PID 6650 | Values: 101.0 114.0 106.0 121.0 118.0 115.0 115.0 103.0 114.0 113.0 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | [#1] audio-beat-detection Cycles:10 Average:141.80 Median:153.00 stddev:21.51 (14.1%) stddev-sans-first:22.01 10:43:37 INFO - PID 6650 | Values: 158.0 144.0 152.0 169.0 142.0 158.0 127.0 106.0 154.0 108.0 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | [#2] audio-dft Cycles:10 Average:199.70 Median:201.00 stddev:2.58 (1.3%) stddev-sans-first:2.67 10:43:37 INFO - PID 6650 | Values: 198.0 201.0 197.0 201.0 202.0 195.0 202.0 198.0 203.0 200.0 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | [#3] audio-fft Cycles:10 Average:80.60 Median:62.00 stddev:33.82 (54.6%) stddev-sans-first:35.66 10:43:37 INFO - PID 6650 | Values: 70.0 84.0 74.0 157.0 57.0 56.0 62.0 59.0 125.0 62.0 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:59.50 stddev:34.39 (57.8%) stddev-sans-first:35.00 10:43:37 INFO - PID 6650 | Values: 69.0 76.0 131.0 65.0 54.0 75.0 141.0 100.0 104.0 151.0 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | [#5] imaging-gaussian-blur Cycles:10 Average:95.60 Median:90.00 stddev:13.08 (14.5%) stddev-sans-first:13.72 10:43:37 INFO - PID 6650 | Values: 90.0 91.0 89.0 90.0 91.0 131.0 91.0 90.0 90.0 103.0 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | [#6] imaging-darkroom Cycles:10 Average:106.40 Median:96.00 stddev:21.14 (22.0%) stddev-sans-first:21.94 10:43:37 INFO - PID 6650 | Values: 94.0 96.0 101.0 140.0 152.0 95.0 96.0 97.0 96.0 97.0 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | [#7] imaging-desaturate Cycles:10 Average:102.40 Median:91.50 stddev:20.41 (22.3%) stddev-sans-first:21.36 10:43:37 INFO - PID 6650 | Values: 93.0 91.0 103.0 149.0 130.0 92.0 92.0 91.0 94.0 89.0 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:52.00 stddev:1.35 (2.6%) stddev-sans-first:1.12 10:43:37 INFO - PID 6650 | Values: 49.0 49.0 51.0 53.0 52.0 52.0 52.0 52.0 52.0 52.0 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:61.50 stddev:13.17 (21.4%) stddev-sans-first:13.31 10:43:37 INFO - PID 6650 | Values: 53.0 55.0 54.0 63.0 75.0 88.0 84.0 60.0 55.0 56.0 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:79.50 stddev:7.83 (9.9%) stddev-sans-first:8.27 10:43:37 INFO - PID 6650 | Values: 80.0 79.0 76.0 79.0 78.0 78.0 80.0 77.0 94.0 99.0 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | [#11] stanford-crypto-ccm Cycles:10 Average:143.50 Median:147.00 stddev:9.00 (6.1%) stddev-sans-first:9.32 10:43:37 INFO - PID 6650 | Values: 149.0 148.0 126.0 150.0 144.0 144.0 146.0 138.0 133.0 157.0 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.50 Median:175.00 stddev:14.15 (8.1%) stddev-sans-first:14.92 10:43:37 INFO - PID 6650 | Values: 175.0 180.0 174.0 174.0 180.0 219.0 173.0 175.0 172.0 173.0 10:43:37 INFO - PID 6650 | 10:43:37 INFO - PID 6650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.20 Median:78.00 stddev:14.60 (18.7%) stddev-sans-first:14.80 10:43:37 INFO - PID 6650 | Values: 64.0 64.0 66.0 63.0 63.0 73.0 83.0 95.0 98.0 93.0 10:43:37 INFO - PID 6650 | -------- Summary: end -------- 10:43:37 INFO - PID 6650 | 10:43:38 INFO - TEST-INFO | 6650: exit 0 10:43:38 INFO - TEST-OK | kraken | took 88620ms 10:43:38 INFO - SUITE-END | took 1230s 10:43:39 INFO - Completed test suite (00:20:30) 10:43:39 INFO - javascript benchmark 10:43:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8621.781218522803, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2997.3106185577317, 3132.37352529494, 3277.9344413111735, 3678.4482327650867, 3769.2649933263024, 6338.80983570493, 6358.14165087713, 6371.235451745785, 6376.713047912844, 6388.936110638898, 6447.935520644792, 6481.449076828475, 6490.643014634194, 6495.155629768135, 6544.541882068257, 5666.405027472118, 5716.456936591027, 5732.369439361667, 5854.18041474194, 5871.70641467927, 5166.819161329354, 5770.134479828017, 5845.853853653672, 5850.122481627747, 5877.206577112078, 5053.797848086072, 5245.86885327866, 5711.485966263035, 5752.856178595527, 5766.077427611583, 5638.4925356718095, 5657.8019769307875, 5718.971405142989, 5746.971265143689, 5813.593048486608, 5673.23411339468, 5674.3190817101795, 5736.913946290809, 5781.103928891029, 5781.277340332458, 5757.848430313912, 5786.88426231473, 6470.158879345702, 6518.0548820421245, 6536.11762412073, 7078.433725301964, 7120.359167674934, 7152.105986751656, 7159.463040272, 7165.283471652793, 6896.241413444517, 6950.965245173792, 6993.2307446181385, 7085.929140708578, 7212.567245965259, 6552.312007239267, 6676.766313179064, 6686.5653732507235, 6718.428933540655, 6726.159230096238, 7405.5926924019695, 7407.407407407394, 7417.629118544051, 7427.888581671279, 7442.21856705049, 6706.664666766642, 6852.965735171292, 6987.685554150075, 7000.92999070013, 7018.0, 7035.964820175969, 7036.155661320674, 7037.19072306681, 7099.3965512930945, 7226.963865180641, 7017.9649101755185, 7038.507304488637, 7100.325469080429, 7107.466939979522, 7107.680154393065, 7108.786736397917, 7130.500864939509, 7133.108361454818, 7140.7500737474975, 7143.178534468602, 5683.54531637468, 5686.914696279559, 5694.572907031989, 5702.429757024264, 5721.799737009184, 4886.364772579542, 4925.1873440881855, 5009.924851127236, 5022.347094877642, 5062.190049592047, 6380.553361264667, 6450.225972883284, 6471.676416179266, 6611.603303801788, 6717.764878229238, 7224.638768061681, 7238.710451581983, 7244.927550724531, 7259.600721960237, 7289.963550182215, 6700.597964122168, 6704.463642908556, 6716.597004179765, 6717.294684058202, 6724.562903411361, 6234.657093859791, 6331.0, 6340.619562826245, 6355.936440635627, 6360.586561873463, 6457.903131452938, 7168.139823221039, 7347.963260183878, 7452.598183242082, 7480.8877866832045, 6191.226096737908, 7068.823279418056, 7088.432925365855, 7107.75122870697, 7238.674259658223, 7386.926130738624, 7446.292602202782, 7452.844809054605, 7474.5888976106835, 7475.850482990419, 7121.359077683033, 7134.250903655042, 7147.892781608176, 7150.5352152109945, 7159.284071592799, 7298.817529561803, 7304.19653838088, 7308.232635573188, 7312.96343518279, 7329.52358096722, 6763.256041835296, 6852.691628876612, 7034.437245020488, 7055.506114571931, 7068.540544864567, 5683.664338880442, 5934.6142500737315, 6784.6946887389195, 6922.7923162305215, 7033.226345101933, 5923.911141332797, 6791.252962174058, 6853.691583878837, 6873.725050997904, 6900.102986611909, 7025.68384422692, 7029.929700703132, 7031.296870312928, 7032.226455090038, 7048.083749112582, 6620.63586502747, 6683.832904177434, 6691.899621505779, 6788.185417749902, 6869.278725733926], "value": 6440.515003732344, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [815.9714409995652, 859.7119964811787, 872.6378552900545, 887.9778005549863, 891.670082069634, 38819.80590097049, 39611.0, 39628.80185599075, 39629.60370396295, 39661.0, 38826.223475530474, 39075.21849563007, 39108.60891391085, 39217.803910980474, 39602.0, 6724.0, 7702.152763196044, 7702.152763196072, 7793.883091753628, 7829.49108307961, 7725.652345644459, 7843.450958432912, 8359.498430094183, 8595.0, 8615.310775137974, 8577.871331930026, 8600.655973761073, 8642.049374568793, 8642.611082501271, 8649.524276164808, 8547.572621368907, 8572.785680357978, 8573.657053717843, 8621.094785047542, 8631.611577479029, 8424.873626895602, 8426.325893928532, 8447.788805279855, 8448.155184481564, 8473.237408633191, 36674.44988325151, 36733.0, 36901.63098369009, 36922.63077369246, 36957.81521092404, 3655.378585640421, 3683.3001729671278, 3698.85204591816, 3704.814759262053, 3708.9072773180615, 30192.0, 30225.546616800766, 30227.0, 30237.546436803466, 30258.54612180819, 3218.5333126696733, 3271.689189526991, 3284.1625385526536, 3289.4243507386113, 3313.8840140595203, 266920.0, 271930.28069719445, 272429.0, 329719.0, 332572.0, 41200.58799412027, 48327.0, 48629.270560941615, 48720.51279487231, 48999.265011024865, 50444.74777626162, 50619.0, 50673.74663126661, 50677.0, 50703.0, 15937.36250549965, 16019.599510012344, 16027.358905643643, 16047.358105675643, 16115.0, 13085.0, 13153.802692959613, 13203.669908252179, 13230.0, 13267.800982985264, 13761.31193440324, 13777.517786877612, 13784.241866697228, 13826.377812998438, 13885.097468664504, 5997.460228579362, 6064.211652485149, 6067.423594758491, 6070.210872586624, 6076.939230607637, 921.8571121476182, 930.0383956352248, 973.7662960889477, 1002.3033991376069, 1010.6563768318659, 22873.771262287497, 23377.88311058434, 23380.415489612897, 23421.0, 24023.399415014763, 982.6609819612222, 1083.864516935383, 1149.494222542079, 1181.285322379965, 1186.7745128425572, 12071.758564828655, 12496.625101246977, 12502.624921252376, 12515.249085054926, 12516.3741812909, 313661.0, 313871.86128138885, 315353.42323288234, 316334.41832790687, 317018.0, 54047.72976135173, 54110.18834717482, 54181.45818541764, 54222.64443388868, 54348.45651543513, 1539.1534655939188, 1618.6034421566792, 1620.8622267107428, 1621.7891674082293, 1624.6669432766255, 20351.898240508704, 22779.88610056939, 22791.0, 23418.64872026887, 23460.0, 38226.617733822306, 38409.807950960065, 38438.423423648106, 38467.80766096152, 38824.417633736084, 264681.0, 265297.0, 265764.0, 265772.0, 265837.0, 30037.249068773457, 30392.392152157274, 30414.54378184373, 30424.84787576048, 34890.65109348874, 35380.23098844996, 36113.0, 39631.0, 41892.0, 44468.332975004756, 27661.58507622347, 28980.0, 29359.559606606344, 29765.702342977158, 30947.381052378376, 29133.16072573524, 29564.408711826072, 30803.691963080084, 30922.942425377794, 31364.843175783975, 34658.306833862676, 36745.63254367422, 36919.44620830743, 36937.07657308589, 36997.63002369942, 23865.76070429731, 29226.123216303546, 29756.851215743784, 30149.547756783228, 31117.8444107778, 18444.26222951067, 22415.0, 23713.881430592737, 23764.881175594703, 24135.39661508406], "value": 17393.27473501639, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.8027369325963, 531.9069312562682, 538.4185080113477, 545.9099248623977, 549.1076999875203, 22067.558648827013, 23887.28338149854, 24105.192110591688, 24154.87922560389, 24162.27513174603, 25354.619680704804, 27906.581401278945, 28131.437371252614, 28200.576991345148, 28208.576871346893, 6563.868722625557, 6664.100346453215, 6680.966595167018, 6728.865422691543, 6739.797806065826, 6687.629036047599, 6708.698108585126, 6714.127163468742, 6716.026176204447, 6726.697298621574, 6122.448979591836, 6478.902816457757, 6627.204735431731, 6653.401193892549, 6688.83277918051, 6636.270010298863, 6637.303083176269, 6650.5344625876205, 6677.432418244431, 6689.130413046273, 5163.7676304566385, 6126.6017708848785, 6170.382961703838, 6171.814845554641, 6175.3515880832765, 27063.45873082547, 29151.271218219503, 29522.26194345137, 29597.70402295971, 30664.386712265852, 2340.157543284416, 2371.1700904683394, 2382.4401265702545, 2400.5558971590312, 2401.543706695722, 16526.0, 19689.507762305915, 20244.79755202444, 20245.29141480056, 20279.0, 2202.2512345802343, 2227.287268074217, 2255.233220704968, 2257.390504563775, 2266.9886650566805, 200060.0, 226223.0, 228886.0, 231460.0, 237595.0, 16115.516534503984, 16291.429799956944, 16538.173091345383, 17008.0, 17144.39994600183, 21934.0, 22054.228102016354, 22180.667289990663, 22255.666165007537, 22261.0, 10919.890801091888, 10966.232363734622, 10985.780284394268, 11096.389698566494, 11116.999470047735, 9512.667056652985, 10239.84640230397, 10295.3822770634, 10317.27779055474, 10381.532831022621, 9526.714198574055, 9681.080297371738, 9737.853932191023, 9755.317127800985, 9774.902250977399, 2762.240383894445, 2768.4601502707183, 2778.347088434257, 2779.4580056888713, 2779.8332100074063, 967.8112768010311, 983.9811371204884, 990.5839547390113, 1039.5218199628255, 1042.358949246208, 19506.0, 21705.565888682137, 21950.890245548988, 22527.774722252896, 22863.08547658075, 1005.1606908231709, 1039.1998161415818, 1048.758785479344, 1072.292287090532, 1075.956961721538, 10171.338862973884, 11149.609763658233, 11401.600943967087, 11411.771764564663, 11736.295822250693, 537333.0, 541551.0, 542064.0, 557828.0, 560591.0, 143130.0, 145910.0, 147459.2627036858, 148316.0, 148487.0, 1161.1289746337816, 1161.89542941134, 1208.0396085112357, 1208.4441157067672, 1216.294549161502, 16878.409255675993, 19391.933443660735, 19480.415587532396, 19645.803541964397, 20051.897145657127, 52085.0, 62543.0, 64537.0, 67155.656886861, 67933.66033169802, 90586.0, 90896.545517272, 91149.0, 92110.53944730233, 92313.53843230741, 12022.579209727617, 12087.153899226969, 12121.151519393557, 12222.572209972608, 12243.449044793184, 13553.051286409856, 18217.45347639573, 18525.0, 18611.627767444304, 18886.811131889055, 15356.462523811613, 15422.383104676137, 15434.382624695336, 15640.530784076496, 15690.372385104469, 11661.125415593695, 11831.349275789915, 13518.93240533791, 13912.930435347758, 14284.285785710465, 15835.683286334439, 15927.761083583988, 15980.360785568448, 16202.918985404998, 16203.75694364609, 12032.398380081137, 12081.0, 12122.0, 12141.514339426323, 12166.817497737718, 15032.6993460128, 15206.923965380101, 15290.923545382202, 15340.616484587974, 15520.689586208438], "value": 12130.334073098698, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1528.7706843973403, 1558.742807436773, 1583.6753465539566, 1592.2675569238143, 1606.6947280016796, 14281.500147494828, 14784.039037462557, 14829.55511334659, 14859.925700371483, 14882.851171488282, 12286.63140105796, 13797.103188292755, 14553.708925821476, 14604.977651564395, 14634.414623415078, 2902.5210840211394, 2943.411317736461, 2975.5536669499534, 2977.865996030184, 2978.3447641518833, 2896.493535498912, 2916.4167166566667, 2923.4591600553968, 2942.161483977067, 2945.057581573897, 2444.2545023767743, 2919.970800292002, 2927.3267148555738, 2928.209383466463, 2942.764578833699, 2870.0815738963433, 2898.2899189698446, 2899.9855000724965, 2902.2309088091674, 2906.9127926162355, 2854.9714502854913, 2877.1943855720433, 2879.6544414670166, 2889.0321742216383, 2895.3774938388347, 14200.786988195185, 14201.644958876008, 14215.857841421557, 14355.71288574233, 14367.281635918162, 7865.134835168127, 8124.715634952749, 8152.0, 8164.265216130518, 8173.346132309401, 4284.871453856389, 4785.64107691921, 5037.974810125963, 5141.974290128525, 5150.51070148339, 1427.3790900958013, 1467.1270593996555, 1482.8517148285193, 1484.2949598940525, 1490.8360080391149, 21280.04239809197, 21377.144914203414, 21383.5723285535, 21385.0, 21480.785192148036, 5794.449527294984, 5922.970385148046, 5988.401159884064, 6436.8390790230615, 6908.938514039342, 14840.703185936221, 14842.777358339634, 14857.851421485862, 14877.702445951021, 14919.552413427615, 7695.653695583712, 7785.3771698264, 7852.489588176751, 7865.606719664108, 7874.842503149905, 5990.940090599038, 6204.875902481925, 6350.301466838644, 6832.897506537406, 7345.669444874993, 5254.632175747661, 5503.284573005483, 5508.504234618824, 5581.5813813963305, 5605.383407825136, 1594.840515948396, 1614.2413065859027, 1615.3780794394243, 1629.0470075006017, 1640.425850952181, 800.1358532784578, 838.828040251747, 842.1031601344671, 845.8139209373928, 864.6584599083327, 5744.339400968858, 6985.441164706811, 7190.280971902873, 7343.963280183565, 7477.588732619649, 622.2843729710778, 672.8351553869334, 705.5766540076016, 722.8903632923437, 739.7189068154067, 4251.341042138473, 4863.027394521039, 5301.289097631673, 5330.200469929464, 5480.945190548123, 18997.0, 19076.236950521823, 19164.520886977938, 19262.0, 19380.8061919379, 18572.25710971573, 18804.811951880307, 18834.15246313919, 19182.136803843583, 19382.12780424884, 9939.503024848873, 10073.949630251802, 10153.340032897986, 10160.13638840692, 10217.33587316822, 6705.262421133769, 8425.536595487061, 8447.366447516339, 8472.49165050099, 8762.430442021248, 17279.65440691154, 17399.913000434917, 17535.912320438318, 17677.558061048578, 17841.643167136845, 16277.511674649779, 16463.60059394965, 16766.0, 17737.0, 17770.91114544419, 9504.857427138737, 10288.691339259834, 10601.628942986958, 11294.04000659953, 11521.942390287995, 12116.575919842764, 12237.449314780679, 12945.223286602833, 13467.191968481922, 13704.451821927012, 10896.836547451636, 11097.778044439228, 11127.443627818415, 11353.205275630626, 11584.189106762446, 10564.366138031743, 10723.356598604109, 11113.611023614134, 11181.440927953407, 11199.160062995144, 9432.292578056811, 9475.24198064167, 9489.525523723923, 9493.193078588121, 9541.666041688508, 6297.937020629918, 6857.862842743018, 6866.759663411956, 7102.8635418332815, 7182.60495672743, 6929.3070692930305, 7131.607761573163, 7326.4138868889295, 7362.190159082389, 7390.187079421152], "value": 6331.081655839422, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.7002683269291, 484.60020483101437, 492.9038837426701, 503.647446787249, 505.801251035047, 5486.588505862056, 5596.300462442195, 6723.731050757976, 6738.292479289666, 6780.660966951657, 6316.810495685137, 6552.770653027145, 6553.606783592976, 6570.244421891484, 6582.572132811376, 4941.827036053739, 4960.826371077004, 4995.925061124086, 4999.32509111269, 5003.674761140514, 4980.900381992358, 4990.3762029746285, 4994.275830004647, 4999.8000079996755, 5014.5737612303, 4292.399064131024, 4647.838040489877, 4953.306537084811, 4954.455009948902, 4955.578775804043, 3820.7134464915243, 4792.113459010059, 4820.831270905536, 4843.346148269974, 4915.778789954461, 3749.268892565951, 4261.509926358446, 4358.36803663467, 4386.188555117314, 4389.978050109761, 6379.266384365811, 6395.648239346834, 6405.711742971577, 6450.8064758057335, 6452.580582262138, 5882.911661342665, 5884.7940322088525, 5886.23478947734, 5898.970505147489, 5969.611975221596, 5862.384449632812, 7016.894746578805, 7086.751963681247, 7091.432685385208, 7099.503034787567, 4955.182394904837, 4961.801527938878, 4985.451600323961, 4988.501149885019, 5015.1975683890405, 7084.220735719066, 7423.962880185619, 7428.291492956596, 7499.175090740015, 7500.662470188855, 4333.58830911063, 5063.088644044034, 5089.982003599295, 5091.847244582668, 5110.591152707774, 5160.48395160481, 5195.428502864757, 5207.140821764367, 5213.348331458567, 5213.6089793265655, 5859.882802343929, 5863.149843272701, 5880.764769409261, 5896.233489646404, 5898.380670029671, 7092.680829362588, 7101.857962840714, 7115.573065615976, 7118.537295075907, 7195.748148814766, 1633.289519059213, 1642.8603568696556, 1696.642685851314, 1764.4177421451147, 1767.1959258537336, 1176.764397382762, 1188.3701638131804, 1201.0031673710798, 1214.8481439820023, 1218.165556593736, 6557.278699343068, 6721.529492935448, 6726.0158253315385, 6749.460043196533, 6838.897416538756, 6665.900011499832, 7086.574805511686, 7393.55638661682, 7435.814104647319, 7453.291937265951, 6715.563488373338, 6717.193936727624, 6727.899081513781, 6738.494612904052, 6776.186857577122, 3407.84133394647, 3619.3304238715396, 3832.1186127190344, 3852.4221366795205, 3862.4978752761963, 5801.3038435388025, 6544.018397240452, 7327.303906128909, 7604.8479030420185, 7634.923650763421, 6872.587644741331, 7456.078987867981, 7533.8116547085665, 7551.622418879034, 7584.028152680343, 6526.4452521536205, 6647.86704265912, 6978.232394436608, 7497.325240728252, 7609.467337286558, 6701.631410272482, 7269.382102521345, 7283.344498995116, 7359.963200183965, 7386.2613738625705, 7168.569885806868, 7173.569585824867, 7209.423246140192, 7225.602591857499, 7263.636818158965, 6652.634105124071, 6922.550034247758, 7136.250693677297, 7634.4655874088285, 7658.387329013777, 5036.169032109661, 5051.065552872583, 5058.367704036996, 5072.771725272298, 5090.7709153088945, 7393.593352365671, 7396.445266604918, 7399.852002959803, 7433.70265189408, 7505.924940750522, 5423.023855705853, 7072.186698529735, 7103.786886393416, 7115.181754098138, 7127.679254433459, 6178.691065446799, 6567.194021644086, 7024.227334993045, 7050.9138315992095, 7082.716691332289, 5398.352197736297, 6425.967870160619, 6878.896816547655, 6905.516613836983, 6960.686769095504, 6351.428371446592, 6773.661316934232, 7005.478027909847, 7080.539764915264, 7154.749583764751, 5457.399686034453, 5937.970310148422, 6286.591371560834, 6908.620025898624, 6936.0636314098965], "value": 5239.180035094701, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [829.8055019281065, 883.6112110671305, 890.7728529225046, 897.5773399162327, 906.9727908162755, 15489.0, 16247.106409147522, 16780.496585102435, 17729.379471718476, 18769.06154692265, 14786.334614942327, 14819.259037048132, 14835.851641483581, 14859.628509287273, 16958.40645577405, 881.2289246908955, 1017.8473229015651, 1046.1317106801337, 1049.4805071489623, 1053.6048981631889, 1050.6637875879721, 1052.8894466081028, 1055.8521806946994, 1056.2183983851933, 1057.619257067455, 1081.3944191252924, 1081.6376513867863, 1082.1775450657517, 1084.4848696868964, 1084.8264277715566, 995.3381001633904, 1085.7285678580356, 1098.4452851310118, 1104.9779004419947, 1109.1127098321392, 799.9200079992013, 809.1221025187679, 817.3502065857598, 821.6713314674118, 822.3174764945081, 18474.538136546693, 18765.967871767047, 18785.15466803997, 18982.905085474624, 18990.955497447634, 18386.63226735471, 18519.907400463046, 18643.34748283817, 18825.152868120967, 19019.61960760791, 15466.0, 15486.5354039379, 15487.303071361815, 15548.844511554853, 15643.452479163288, 3685.870994515206, 3793.6775374093236, 3795.905102372435, 3804.6385593368586, 3831.3678243089857, 155510.0, 160992.19503902405, 163696.18151909165, 164582.0, 168705.0, 14171.362288696828, 14267.78598321026, 14405.92797036008, 14439.639009024648, 14548.854511454962, 26903.192904212905, 28705.28236794097, 28847.990320338686, 29039.41921161565, 29098.127056188347, 8120.512769233865, 8158.3065439438315, 8176.46852954556, 8210.917890821134, 8213.79465513367, 4058.3303754880703, 4118.897027574335, 4163.875083747493, 4167.8958026049595, 4168.291390463629, 4064.4716186896108, 4619.145458090264, 4965.851024469272, 4986.0277245936695, 4994.825181118644, 1011.5397494140149, 1017.8524114003576, 1019.1642852860583, 1028.8508166315992, 1035.9689209323733, 655.8884989551788, 668.5554106519111, 669.0800149794034, 677.5054210426416, 704.2394214248599, 10398.0, 12150.939245303716, 12234.694132646755, 12412.441440135215, 12538.498460061495, 679.5141473846223, 686.5400181878153, 689.8482333886537, 695.2387136085978, 712.9786106416713, 5401.810936617199, 5669.574781891375, 5770.596058275881, 6007.669578173242, 6034.1853849730605, 130625.0, 132903.0, 135478.0, 141060.0, 142578.0, 110266.0, 111335.20653554173, 112133.0, 118630.22054669187, 126031.73968260383, 85025.87435314043, 100113.0, 100836.0, 108476.37285440725, 108885.0, 11449.885501144881, 11754.529818807152, 12635.557755478514, 13227.3386330685, 13267.469301227842, 45972.770136149105, 47417.76291118522, 47532.7623361881, 48531.5146848527, 50143.498565013884, 63611.0, 64489.35510644833, 67464.66267668827, 68077.65961170361, 68928.31071689219, 17409.651806963535, 17986.100694965462, 18009.549761256072, 18209.271629134688, 18399.908000460448, 19009.0, 19027.619447611247, 19202.0, 19215.943123128363, 19513.024348782787, 15746.0, 17202.65594688124, 18027.909860450614, 18094.728579071587, 18622.81377186265, 15587.142707151215, 15784.842151578338, 17347.913260433616, 18731.90634046821, 19086.904565477085, 14648.487302944772, 17546.21042053085, 18197.818021819614, 18404.815951840847, 18410.987395693366, 11956.0, 12040.157188996685, 12465.688357791129, 12836.358182091044, 13212.669683257995, 13910.721785564434, 14274.357653905392, 14442.53379189008, 14507.92746036263, 14905.403783848524], "value": 9112.57091512411, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1532.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 106.0, 121.0, 118.0, 115.0, 115.0, 103.0, 114.0, 113.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 144.0, 152.0, 169.0, 142.0, 158.0, 127.0, 106.0, 154.0, 108.0], "value": 141.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 201.0, 197.0, 201.0, 202.0, 195.0, 202.0, 198.0, 203.0, 200.0], "value": 199.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 84.0, 74.0, 157.0, 57.0, 56.0, 62.0, 59.0, 125.0, 62.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 131.0, 65.0, 54.0, 75.0, 141.0, 100.0, 104.0, 151.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 89.0, 90.0, 91.0, 131.0, 91.0, 90.0, 90.0, 103.0], "value": 95.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 101.0, 140.0, 152.0, 95.0, 96.0, 97.0, 96.0, 97.0], "value": 106.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 103.0, 149.0, 130.0, 92.0, 92.0, 91.0, 94.0, 89.0], "value": 102.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 51.0, 53.0, 52.0, 52.0, 52.0, 52.0, 52.0, 52.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 54.0, 63.0, 75.0, 88.0, 84.0, 60.0, 55.0, 56.0], "value": 64.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 76.0, 79.0, 78.0, 78.0, 80.0, 77.0, 94.0, 99.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 126.0, 150.0, 144.0, 144.0, 146.0, 138.0, 133.0, 157.0], "value": 143.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 180.0, 174.0, 174.0, 180.0, 219.0, 173.0, 175.0, 172.0, 173.0], "value": 179.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 66.0, 63.0, 63.0, 73.0, 83.0, 95.0, 98.0, 93.0], "value": 76.2, "unit": "score"}]}]} 10:43:39 INFO - Return code: 0 10:43:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:43:39 INFO - # TBPL SUCCESS # 10:43:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:43:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:43:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:43:39 INFO - 2017-06-13 10:43:39.316 screenresolution[6657:122591] starting screenresolution argv=screenresolution get 10:43:39 INFO - 2017-06-13 10:43:39.318 screenresolution[6657:122591] Display 0: 1600x1200x32@60 10:43:39 INFO - 2017-06-13 10:43:39.324 screenresolution[6658:122594] starting screenresolution argv=screenresolution list 10:43:39 INFO - Available Modes on Display 0 10:43:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:43:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:43:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:43:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:43:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:43:39 INFO - Intel Iris: 10:43:39 INFO - Chipset Model: Intel Iris 10:43:39 INFO - Type: GPU 10:43:39 INFO - Bus: Built-In 10:43:39 INFO - VRAM (Dynamic, Max): 1536 MB 10:43:39 INFO - Vendor: Intel (0x8086) 10:43:39 INFO - Device ID: 0x0a2e 10:43:39 INFO - Revision ID: 0x0009 10:43:39 INFO - Displays: 10:43:39 INFO - Display: 10:43:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:43:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:43:39 INFO - Display Serial Number: 1600x1200 60 10:43:39 INFO - Main Display: Yes 10:43:39 INFO - Mirror: Off 10:43:39 INFO - Online: Yes 10:43:39 INFO - Rotation: Supported 10:43:39 INFO - Return code: 0 10:43:39 INFO - Running post-action listener: _resource_record_post_action 10:43:39 INFO - [mozharness: 2017-06-13 17:43:39.561736Z] Finished run-tests step (success) 10:43:39 INFO - Running post-run listener: _resource_record_post_run 10:43:39 INFO - Total resource usage - Wall time: 1247s; CPU: 53.0%; Read bytes: 3633152; Write bytes: 469733888; Read time: 40; Write time: 1534 10:43:39 INFO - TinderboxPrint: CPU usage
53.1% 10:43:39 INFO - TinderboxPrint: I/O read bytes / time
3,633,152 / 40 10:43:39 INFO - TinderboxPrint: I/O write bytes / time
469,733,888 / 1,534 10:43:39 INFO - TinderboxPrint: CPU idle
2,338.8 (46.9%) 10:43:39 INFO - TinderboxPrint: CPU system
1,018.4 (20.4%) 10:43:39 INFO - TinderboxPrint: CPU user
1,632.4 (32.7%) 10:43:39 INFO - TinderboxPrint: Swap in / out
158,482,432 / 0 10:43:39 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 154296832; Write bytes: 154214400; Read time: 12066; Write time: 343 10:43:39 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:43:39 INFO - run-tests - Wall time: 1232s; CPU: 53.0%; Read bytes: 3440640; Write bytes: 314036736; Read time: 36; Write time: 1181 10:43:40 INFO - Running post-run listener: _upload_blobber_files 10:43:40 INFO - Blob upload gear active. 10:43:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:43:40 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 10:43:40 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'] 10:43:40 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 10:43:40 INFO - (blobuploader) - INFO - Open directory for files ... 10:43:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:43:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:43:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:41 INFO - (blobuploader) - INFO - Done attempting. 10:43:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:43:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:43:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:43 INFO - (blobuploader) - INFO - Done attempting. 10:43:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:43:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:43:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:44 INFO - (blobuploader) - INFO - Done attempting. 10:43:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:43:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:43:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:46 INFO - (blobuploader) - INFO - Done attempting. 10:43:46 INFO - (blobuploader) - INFO - Iteration through files over. 10:43:46 INFO - Return code: 0 10:43:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:43:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:43:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49e24f7e171272d2ec506bff1fea5084c320cd7aa07f092dc6c67276ac62f9baf9bd472f364f6e36da2425960463f404906cbcb1fd94dd318c899b6684b3e910", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d85c24fc56016ee311303492150e0c0f0fe5cb7343d0b170924f46a1ab2e41eb3dd1e652b47a5f9cc52d7fbe4193c544430dde04f7d43bbb600340f472c09751", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8ca17a53a6319e47feee98b6b991d282f84cc5063bce9ca1cf563994f547404b72e515e724707364df538c8351e833acf1953f7546ce9814388cc2cfcb92a047", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30fede264ad98275da86aca4736f30741795ebf5ec4e4bf17ae85f7976fe0ee96dcb1b6cab02b93f0c07b726bf3b306bb618a6379764c380b084e166f925ac8a"} 10:43:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:43:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:43:46 INFO - Contents: 10:43:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49e24f7e171272d2ec506bff1fea5084c320cd7aa07f092dc6c67276ac62f9baf9bd472f364f6e36da2425960463f404906cbcb1fd94dd318c899b6684b3e910", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d85c24fc56016ee311303492150e0c0f0fe5cb7343d0b170924f46a1ab2e41eb3dd1e652b47a5f9cc52d7fbe4193c544430dde04f7d43bbb600340f472c09751", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8ca17a53a6319e47feee98b6b991d282f84cc5063bce9ca1cf563994f547404b72e515e724707364df538c8351e833acf1953f7546ce9814388cc2cfcb92a047", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30fede264ad98275da86aca4736f30741795ebf5ec4e4bf17ae85f7976fe0ee96dcb1b6cab02b93f0c07b726bf3b306bb618a6379764c380b084e166f925ac8a"} 10:43:46 INFO - Running post-run listener: copy_logs_to_upload_dir 10:43:46 INFO - Copying logs to upload dir... 10:43:46 INFO - mkdir: /builds/slave/test/build/upload/logs 10:43:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1328.809819 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2017-06-13 10:43:46.695160) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 10:43:46.702345) ========= 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.6jG53hyTO2/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.XVNaxBLO2m/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49e24f7e171272d2ec506bff1fea5084c320cd7aa07f092dc6c67276ac62f9baf9bd472f364f6e36da2425960463f404906cbcb1fd94dd318c899b6684b3e910", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d85c24fc56016ee311303492150e0c0f0fe5cb7343d0b170924f46a1ab2e41eb3dd1e652b47a5f9cc52d7fbe4193c544430dde04f7d43bbb600340f472c09751", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8ca17a53a6319e47feee98b6b991d282f84cc5063bce9ca1cf563994f547404b72e515e724707364df538c8351e833acf1953f7546ce9814388cc2cfcb92a047", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30fede264ad98275da86aca4736f30741795ebf5ec4e4bf17ae85f7976fe0ee96dcb1b6cab02b93f0c07b726bf3b306bb618a6379764c380b084e166f925ac8a"} build_url:https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011195 build_url: 'https://queue.taskcluster.net/v1/task/KMxxOEyxQbWC71xV6BIJrQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49e24f7e171272d2ec506bff1fea5084c320cd7aa07f092dc6c67276ac62f9baf9bd472f364f6e36da2425960463f404906cbcb1fd94dd318c899b6684b3e910", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d85c24fc56016ee311303492150e0c0f0fe5cb7343d0b170924f46a1ab2e41eb3dd1e652b47a5f9cc52d7fbe4193c544430dde04f7d43bbb600340f472c09751", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8ca17a53a6319e47feee98b6b991d282f84cc5063bce9ca1cf563994f547404b72e515e724707364df538c8351e833acf1953f7546ce9814388cc2cfcb92a047", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30fede264ad98275da86aca4736f30741795ebf5ec4e4bf17ae85f7976fe0ee96dcb1b6cab02b93f0c07b726bf3b306bb618a6379764c380b084e166f925ac8a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 10:43:46.737734) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:43:46.738091) ========= 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.6jG53hyTO2/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.XVNaxBLO2m/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005351 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:43:46.786776) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 10:43:46.795687) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 10:43:46.796072) ========= ========= Total master_lag: 0.45 =========