builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0310 starttime: 1496336717.7 results: success (0) revision: d8bdb186b2ca23ebb72984c16a1578947819bacf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 10:05:17.701184) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 10:05:17.701653) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 10:05:17.701930) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 10:05:17.727752) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:05:17.728141) ========= 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.mOYnclJfzA/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.Eav41kw1aE/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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-01 10:05:17-- 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% 20.5M=0.001s 2017-06-01 10:05:17 (20.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.075842 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:05:17.827346) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:05:17.827663) ========= 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.mOYnclJfzA/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.Eav41kw1aE/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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.040171 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:05:17.888572) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-01 10:05:17.888908) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev d8bdb186b2ca23ebb72984c16a1578947819bacf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/cedar --rev d8bdb186b2ca23ebb72984c16a1578947819bacf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mOYnclJfzA/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.Eav41kw1aE/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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-01 10:05:17,989 truncating revision to first 12 chars 2017-06-01 10:05:17,989 Setting DEBUG logging. 2017-06-01 10:05:17,989 attempt 1/10 2017-06-01 10:05:17,989 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/d8bdb186b2ca?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 10:05:19,730 unpacking tar archive at: cedar-d8bdb186b2ca/testing/mozharness/ program finished with exit code 0 elapsedTime=2.028627 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-01 10:05:19.939488) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 10:05:19.939806) ========= script_repo_revision: d8bdb186b2ca23ebb72984c16a1578947819bacf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 10:05:19.940225) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 10:05:19.940504) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 10:05:19.959427) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-01 10:05:19.959820) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mOYnclJfzA/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.Eav41kw1aE/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:05:20 INFO - MultiFileLogger online at 20170601 10:05:20 in /builds/slave/test 10:05:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 10:05:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:05:20 INFO - {'append_to_log': False, 10:05:20 INFO - 'base_work_dir': '/builds/slave/test', 10:05:20 INFO - 'blob_upload_branch': 'Cedar', 10:05:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:05:20 INFO - 'branch': 'Cedar', 10:05:20 INFO - 'buildbot_json_path': 'buildprops.json', 10:05:20 INFO - 'config_files': ('talos/mac_config.py',), 10:05:20 INFO - 'default_actions': ('clobber', 10:05:20 INFO - 'read-buildbot-config', 10:05:20 INFO - 'download-and-extract', 10:05:20 INFO - 'populate-webroot', 10:05:20 INFO - 'create-virtualenv', 10:05:20 INFO - 'install', 10:05:20 INFO - 'setup-mitmproxy', 10:05:20 INFO - 'run-tests'), 10:05:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:05:20 INFO - 'download_minidump_stackwalk': True, 10:05:20 INFO - 'download_symbols': 'ondemand', 10:05:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:05:20 INFO - 'tooltool.py': '/tools/tooltool.py', 10:05:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:05:20 INFO - '/tools/misc-python/virtualenv.py')}, 10:05:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:05:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:05:20 INFO - 'gecko_profile': False, 10:05:20 INFO - 'gecko_profile_interval': 0, 10:05:20 INFO - 'installer_path': 'installer.exe', 10:05:20 INFO - 'log_level': 'info', 10:05:20 INFO - 'log_name': 'talos', 10:05:20 INFO - 'log_to_console': True, 10:05:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:05:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:05:20 INFO - 'opt_config_files': (), 10:05:20 INFO - 'pip_index': False, 10:05:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:05:20 INFO - 'cmd': ('bash', 10:05:20 INFO - '-c', 10:05:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:05:20 INFO - 'enabled': True, 10:05:20 INFO - 'halt_on_failure': False, 10:05:20 INFO - 'name': 'check_screen_resolution'},), 10:05:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:05:20 INFO - 'cmd': ('bash', 10:05:20 INFO - '-c', 10:05:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:05:20 INFO - 'enabled': True, 10:05:20 INFO - 'halt_on_failure': False, 10:05:20 INFO - 'name': 'check_screen_resolution'},), 10:05:20 INFO - 'run_cmd_checks_enabled': True, 10:05:20 INFO - 'suite': 'dromaeojs-e10s', 10:05:20 INFO - 'system_bits': '32', 10:05:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:05:20 INFO - 'title': 't-yosemite-r7-0310', 10:05:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:05:20 INFO - 'use_talos_json': True, 10:05:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:05:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:05:20 INFO - 'work_dir': 'build'} 10:05:20 INFO - [mozharness: 2017-06-01 17:05:20.167581Z] Running clobber step. 10:05:20 INFO - Running pre-action listener: _resource_record_pre_action 10:05:20 INFO - Running main action method: clobber 10:05:20 INFO - rmtree: /builds/slave/test/build 10:05:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:05:22 INFO - Running post-action listener: _resource_record_post_action 10:05:22 INFO - [mozharness: 2017-06-01 17:05:22.645316Z] Finished clobber step (success) 10:05:22 INFO - [mozharness: 2017-06-01 17:05:22.645461Z] Running read-buildbot-config step. 10:05:22 INFO - Running pre-action listener: _resource_record_pre_action 10:05:22 INFO - Running main action method: read_buildbot_config 10:05:22 INFO - Using buildbot properties: 10:05:22 INFO - { 10:05:22 INFO - "project": "", 10:05:22 INFO - "product": "firefox", 10:05:22 INFO - "who": "mconley@mozilla.com", 10:05:22 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:05:22 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 10:05:22 INFO - "repository": "https://hg.mozilla.org/projects/cedar/", 10:05:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 10:05:22 INFO - "stage_platform": "macosx64", 10:05:22 INFO - "basedir": "/builds/slave/test", 10:05:22 INFO - "buildnumber": 2, 10:05:22 INFO - "platform": "yosemite_r7", 10:05:22 INFO - "parent_task_id": "EKy1MfutT4WdMkszJr6IZA", 10:05:22 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:05:22 INFO - "slavebuilddir": "test", 10:05:22 INFO - "taskId": "V1MNj-uvSfWLSNYoPHtq9w", 10:05:22 INFO - "branch": "cedar", 10:05:22 INFO - "script_repo_revision": "default", 10:05:22 INFO - "revision": "d8bdb186b2ca23ebb72984c16a1578947819bacf", 10:05:22 INFO - "slavename": "t-yosemite-r7-0310", 10:05:22 INFO - "repo_path": "projects/cedar" 10:05:22 INFO - } 10:05:22 INFO - Finding installer, test and symbols from parent task. 10:05:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V1MNj-uvSfWLSNYoPHtq9w'}, attempt #1 10:05:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V1MNj-uvSfWLSNYoPHtq9w'}, attempt #1 10:05:23 INFO - Task dependencies: f24ZbtB3RCmGqzUqY0rIwg EKy1MfutT4WdMkszJr6IZA 10:05:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA'}, attempt #1 10:05:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:05:24 INFO - Set test_url: https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/test_packages.json 10:05:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/target.crashreporter-symbols.zip 10:05:24 INFO - Running post-action listener: _resource_record_post_action 10:05:24 INFO - [mozharness: 2017-06-01 17:05:24.004729Z] Finished read-buildbot-config step (success) 10:05:24 INFO - [mozharness: 2017-06-01 17:05:24.004875Z] Running download-and-extract step. 10:05:24 INFO - Running pre-action listener: _resource_record_pre_action 10:05:24 INFO - Running main action method: download_and_extract 10:05:24 INFO - mkdir: /builds/slave/test/build/tests 10:05:24 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:05:24 INFO - trying https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:05:24 INFO - Downloading https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:05:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 10:05:24 INFO - Downloaded 1325 bytes. 10:05:24 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:05:24 INFO - Using the following test package requirements: 10:05:24 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:05:24 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:05:24 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:05:24 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:05:24 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:05:24 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:05:24 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:05:24 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:05:24 INFO - u'jsshell-mac.zip'], 10:05:24 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:05:24 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:05:24 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:05:24 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:05:24 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:05:24 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:05:24 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:05:24 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:05:24 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:05:24 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:05:24 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:05:24 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:05:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:05:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:05:24 INFO - Fetch https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:05:26 INFO - Content-Length response header: 57629693 10:05:26 INFO - Bytes received: 57629693 10:05:44 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:05:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:05:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:05:44 INFO - Fetch https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:05:47 INFO - Content-Length response header: 57629693 10:05:47 INFO - Bytes received: 57629693 10:06:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:06:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:06:04 INFO - Fetch https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:06:05 INFO - Content-Length response header: 14447425 10:06:05 INFO - Bytes received: 14447425 10:06:05 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:06:05 INFO - trying https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:06:05 INFO - Downloading https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:06:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 10:06:07 INFO - Downloaded 67866406 bytes. 10:06:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:06:07 INFO - mkdir: /builds/slave/test/properties 10:06:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:06:07 INFO - Writing to file /builds/slave/test/properties/build_url 10:06:07 INFO - Contents: 10:06:07 INFO - build_url:https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:06:07 INFO - Running post-action listener: _resource_record_post_action 10:06:07 INFO - Running post-action listener: set_extra_try_arguments 10:06:07 INFO - [mozharness: 2017-06-01 17:06:07.527673Z] Finished download-and-extract step (success) 10:06:07 INFO - [mozharness: 2017-06-01 17:06:07.527828Z] Running populate-webroot step. 10:06:07 INFO - Running pre-action listener: _resource_record_pre_action 10:06:07 INFO - Running main action method: populate_webroot 10:06:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:06:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:06:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:06:07 INFO - '--tppagecycles', 10:06:07 INFO - '7'], 10:06:07 INFO - 'tests': ['tsvgm']}}, 10:06:07 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:06:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:06:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:06:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:06:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:06:07 INFO - 'talos_options': ['--disable-e10s'], 10:06:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:06:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:06:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:06:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:06:07 INFO - 'talos_options': ['--disable-e10s'], 10:06:07 INFO - 'tests': ['damp', 'tps']}, 10:06:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:06:07 INFO - 'tests': ['damp', 'tps']}, 10:06:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:06:07 INFO - 'tests': ['dromaeo_dom']}, 10:06:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:06:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:06:07 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:06:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:06:07 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:06:07 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:06:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:06:07 INFO - 'tests': ['a11yr', 10:06:07 INFO - 'ts_paint', 10:06:07 INFO - 'tpaint', 10:06:07 INFO - 'sessionrestore', 10:06:07 INFO - 'sessionrestore_no_auto_restore', 10:06:07 INFO - 'tabpaint']}, 10:06:07 INFO - 'other-e10s': {'tests': ['a11yr', 10:06:07 INFO - 'ts_paint', 10:06:07 INFO - 'tpaint', 10:06:07 INFO - 'sessionrestore', 10:06:07 INFO - 'sessionrestore_no_auto_restore', 10:06:07 INFO - 'tabpaint']}, 10:06:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:06:07 INFO - 'ts_paint', 10:06:07 INFO - 'tpaint', 10:06:07 INFO - 'sessionrestore', 10:06:07 INFO - 'sessionrestore_no_auto_restore', 10:06:07 INFO - 'tabpaint']}, 10:06:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:06:07 INFO - 'ts_paint', 10:06:07 INFO - 'tpaint', 10:06:07 INFO - 'sessionrestore', 10:06:07 INFO - 'sessionrestore_no_auto_restore', 10:06:07 INFO - 'tabpaint']}, 10:06:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:06:07 INFO - 'tests': ['a11yr', 10:06:07 INFO - 'ts_paint', 10:06:07 INFO - 'tpaint', 10:06:07 INFO - 'sessionrestore', 10:06:07 INFO - 'sessionrestore_no_auto_restore', 10:06:07 INFO - 'tabpaint']}, 10:06:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:06:07 INFO - 'tests': ['a11yr', 10:06:07 INFO - 'ts_paint', 10:06:07 INFO - 'tpaint', 10:06:07 INFO - 'sessionrestore', 10:06:07 INFO - 'sessionrestore_no_auto_restore', 10:06:07 INFO - 'tabpaint']}, 10:06:07 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:06:07 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:06:07 INFO - 'bloom_basic_ref']}, 10:06:07 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 10:06:07 INFO - 'talos_options': ['--mitmproxy', 10:06:07 INFO - 'mitmproxy-recording-1.mp', 10:06:07 INFO - '--firstNonBlankPaint'], 10:06:07 INFO - 'tests': ['Quantum_1']}, 10:06:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:06:07 INFO - 'tests': ['tsvgx', 10:06:07 INFO - 'tsvgr_opacity', 10:06:07 INFO - 'tart', 10:06:07 INFO - 'tscrollx', 10:06:07 INFO - 'cart', 10:06:07 INFO - 'tsvg_static']}, 10:06:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:06:07 INFO - 'tsvgr_opacity', 10:06:07 INFO - 'tart', 10:06:07 INFO - 'tscrollx', 10:06:07 INFO - 'cart', 10:06:07 INFO - 'tsvg_static']}, 10:06:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:06:07 INFO - 'talos_options': ['--disable-e10s'], 10:06:07 INFO - 'tests': ['tp5o']}, 10:06:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:06:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:06:07 INFO - 'talos_options': ['--disable-e10s', 10:06:07 INFO - '--xperf_path', 10:06:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:06:07 INFO - 'tests': ['tp5n']}, 10:06:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:06:07 INFO - 'talos_options': ['--xperf_path', 10:06:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:06:07 INFO - 'tests': ['tp5n']}}, 10:06:07 INFO - 'talos.zip': {'path': '', 10:06:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:06:07 INFO - Running post-action listener: _resource_record_post_action 10:06:07 INFO - [mozharness: 2017-06-01 17:06:07.550425Z] Finished populate-webroot step (success) 10:06:07 INFO - [mozharness: 2017-06-01 17:06:07.550575Z] Running create-virtualenv step. 10:06:07 INFO - Running pre-action listener: _resource_record_pre_action 10:06:07 INFO - Running main action method: create_virtualenv 10:06:07 INFO - Creating virtualenv /builds/slave/test/build/venv 10:06:07 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:06:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:06:07 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:06:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:06:07 INFO - Using real prefix '/tools/python27' 10:06:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:06:08 INFO - Installing distribute.............................................................................................................................................................................................done. 10:06:11 INFO - Installing pip.................done. 10:06:11 INFO - Return code: 0 10:06:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:06:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:06:11 INFO - Reading from file tmpfile_stdout 10:06:11 INFO - Output received: 10:06:11 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:06:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:06:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ebec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ec2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8b0493b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e47c60>, '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 0x107bdccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c05be8>, '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.mOYnclJfzA/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.Eav41kw1aE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:06:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:06:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:06:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOYnclJfzA/Render', 10:06:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:11 INFO - 'HOME': '/Users/cltbld', 10:06:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:11 INFO - 'LOGNAME': 'cltbld', 10:06:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:11 INFO - 'MOZ_NO_REMOTE': '1', 10:06:11 INFO - 'NO_EM_RESTART': '1', 10:06:11 INFO - 'PAGER': '/bin/cat', 10:06:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:11 INFO - 'PWD': '/builds/slave/test', 10:06:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:11 INFO - 'SHELL': '/bin/bash', 10:06:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eav41kw1aE/Listeners', 10:06:11 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 10:06:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:11 INFO - 'USER': 'cltbld', 10:06:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:11 INFO - 'XPC_FLAGS': '0x0', 10:06:11 INFO - 'XPC_SERVICE_NAME': '0', 10:06:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:06:11 INFO - Cleaning up... 10:06:11 INFO - Return code: 0 10:06:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:06:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ebec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ec2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8b0493b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e47c60>, '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 0x107bdccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c05be8>, '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.mOYnclJfzA/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.Eav41kw1aE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:06:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:06:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:06:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOYnclJfzA/Render', 10:06:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:11 INFO - 'HOME': '/Users/cltbld', 10:06:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:11 INFO - 'LOGNAME': 'cltbld', 10:06:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:11 INFO - 'MOZ_NO_REMOTE': '1', 10:06:11 INFO - 'NO_EM_RESTART': '1', 10:06:11 INFO - 'PAGER': '/bin/cat', 10:06:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:11 INFO - 'PWD': '/builds/slave/test', 10:06:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:11 INFO - 'SHELL': '/bin/bash', 10:06:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eav41kw1aE/Listeners', 10:06:11 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 10:06:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:11 INFO - 'USER': 'cltbld', 10:06:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:11 INFO - 'XPC_FLAGS': '0x0', 10:06:11 INFO - 'XPC_SERVICE_NAME': '0', 10:06:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:12 INFO - Downloading/unpacking psutil>=3.1.1 10:06:12 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:06:12 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:06:12 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:06:12 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:06:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:06:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:06:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:06:14 INFO - Installing collected packages: psutil 10:06:14 INFO - Running setup.py install for psutil 10:06:14 INFO - building 'psutil._psutil_osx' extension 10:06:14 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:06:14 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:06:14 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:06:14 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:06:14 INFO - building 'psutil._psutil_posix' extension 10:06:14 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:06:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:06:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:06:14 INFO - ^ 10:06:14 INFO - 1 warning generated. 10:06:14 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:06:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:06:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:06:15 INFO - Successfully installed psutil 10:06:15 INFO - Cleaning up... 10:06:15 INFO - Return code: 0 10:06:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:06:15 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:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:15 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:06:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:15 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 0x107ebec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ec2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8b0493b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e47c60>, '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 0x107bdccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c05be8>, '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.mOYnclJfzA/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.Eav41kw1aE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:06:15 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:06:15 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:06:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOYnclJfzA/Render', 10:06:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:15 INFO - 'HOME': '/Users/cltbld', 10:06:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:15 INFO - 'LOGNAME': 'cltbld', 10:06:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:15 INFO - 'MOZ_NO_REMOTE': '1', 10:06:15 INFO - 'NO_EM_RESTART': '1', 10:06:15 INFO - 'PAGER': '/bin/cat', 10:06:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:15 INFO - 'PWD': '/builds/slave/test', 10:06:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:15 INFO - 'SHELL': '/bin/bash', 10:06:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eav41kw1aE/Listeners', 10:06:15 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 10:06:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:15 INFO - 'USER': 'cltbld', 10:06:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:15 INFO - 'XPC_FLAGS': '0x0', 10:06:15 INFO - 'XPC_SERVICE_NAME': '0', 10:06:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:06:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:06:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:06:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:06:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:06:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:06:17 INFO - Installing collected packages: mozsystemmonitor 10:06:17 INFO - Running setup.py install for mozsystemmonitor 10:06:18 INFO - Successfully installed mozsystemmonitor 10:06:18 INFO - Cleaning up... 10:06:18 INFO - Return code: 0 10:06:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:06:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ebec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ec2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8b0493b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e47c60>, '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 0x107bdccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c05be8>, '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.mOYnclJfzA/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.Eav41kw1aE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:06:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:06:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:06:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOYnclJfzA/Render', 10:06:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:18 INFO - 'HOME': '/Users/cltbld', 10:06:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:18 INFO - 'LOGNAME': 'cltbld', 10:06:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:18 INFO - 'MOZ_NO_REMOTE': '1', 10:06:18 INFO - 'NO_EM_RESTART': '1', 10:06:18 INFO - 'PAGER': '/bin/cat', 10:06:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:18 INFO - 'PWD': '/builds/slave/test', 10:06:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:18 INFO - 'SHELL': '/bin/bash', 10:06:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eav41kw1aE/Listeners', 10:06:18 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 10:06:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:18 INFO - 'USER': 'cltbld', 10:06:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:18 INFO - 'XPC_FLAGS': '0x0', 10:06:18 INFO - 'XPC_SERVICE_NAME': '0', 10:06:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:18 INFO - Downloading/unpacking jsonschema==2.5.1 10:06:18 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:06:18 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:06:18 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:06:18 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:06:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:06:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:06:19 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:06:19 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:06:19 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:06:19 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:06:20 INFO - Downloading functools32-3.2.3-2.tar.gz 10:06:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:06:20 INFO - warning: no files found matching '*.txt' 10:06:20 INFO - no previously-included directories found matching 'build' 10:06:20 INFO - no previously-included directories found matching 'dist' 10:06:20 INFO - no previously-included directories found matching '.git*' 10:06:20 INFO - Installing collected packages: jsonschema, functools32 10:06:20 INFO - Running setup.py install for functools32 10:06:20 INFO - warning: no files found matching '*.txt' 10:06:20 INFO - no previously-included directories found matching 'build' 10:06:20 INFO - no previously-included directories found matching 'dist' 10:06:20 INFO - no previously-included directories found matching '.git*' 10:06:20 INFO - Successfully installed jsonschema functools32 10:06:20 INFO - Cleaning up... 10:06:20 INFO - Return code: 0 10:06:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:06:20 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:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:20 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:06:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:20 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 0x107ebec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ec2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8b0493b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e47c60>, '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 0x107bdccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c05be8>, '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.mOYnclJfzA/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.Eav41kw1aE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:06:20 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:06:20 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:06:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOYnclJfzA/Render', 10:06:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:20 INFO - 'HOME': '/Users/cltbld', 10:06:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:20 INFO - 'LOGNAME': 'cltbld', 10:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:20 INFO - 'MOZ_NO_REMOTE': '1', 10:06:20 INFO - 'NO_EM_RESTART': '1', 10:06:20 INFO - 'PAGER': '/bin/cat', 10:06:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:20 INFO - 'PWD': '/builds/slave/test', 10:06:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:20 INFO - 'SHELL': '/bin/bash', 10:06:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eav41kw1aE/Listeners', 10:06:20 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 10:06:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:20 INFO - 'USER': 'cltbld', 10:06:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:20 INFO - 'XPC_FLAGS': '0x0', 10:06:20 INFO - 'XPC_SERVICE_NAME': '0', 10:06:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:06:20 INFO - Cleaning up... 10:06:20 INFO - Return code: 0 10:06:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:06:20 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:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:20 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:06:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:20 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 0x107ebec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ec2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8b0493b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e47c60>, '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 0x107bdccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c05be8>, '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.mOYnclJfzA/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.Eav41kw1aE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:06:20 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:06:20 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:06:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOYnclJfzA/Render', 10:06:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:20 INFO - 'HOME': '/Users/cltbld', 10:06:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:20 INFO - 'LOGNAME': 'cltbld', 10:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:20 INFO - 'MOZ_NO_REMOTE': '1', 10:06:20 INFO - 'NO_EM_RESTART': '1', 10:06:20 INFO - 'PAGER': '/bin/cat', 10:06:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:20 INFO - 'PWD': '/builds/slave/test', 10:06:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:20 INFO - 'SHELL': '/bin/bash', 10:06:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eav41kw1aE/Listeners', 10:06:20 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 10:06:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:20 INFO - 'USER': 'cltbld', 10:06:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:20 INFO - 'XPC_FLAGS': '0x0', 10:06:20 INFO - 'XPC_SERVICE_NAME': '0', 10:06:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:21 INFO - Downloading/unpacking blobuploader==1.2.4 10:06:21 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:06:21 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:06:21 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:06:21 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:06:22 INFO - Downloading blobuploader-1.2.4.tar.gz 10:06:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:06:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:06:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:06:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:06:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:06:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:06:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:06:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:06:23 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:06:23 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:06:23 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:06:23 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:06:23 INFO - Downloading docopt-0.6.1.tar.gz 10:06:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:06:23 INFO - Installing collected packages: blobuploader, requests, docopt 10:06:23 INFO - Running setup.py install for blobuploader 10:06:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:06:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:06:23 INFO - Running setup.py install for requests 10:06:24 INFO - Running setup.py install for docopt 10:06:24 INFO - Successfully installed blobuploader requests docopt 10:06:24 INFO - Cleaning up... 10:06:24 INFO - Return code: 0 10:06:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:06:24 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:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:24 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:06:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:24 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 0x107ebec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ec2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8b0493b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e47c60>, '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 0x107bdccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c05be8>, '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.mOYnclJfzA/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.Eav41kw1aE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:06:24 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:06:24 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:06:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOYnclJfzA/Render', 10:06:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:24 INFO - 'HOME': '/Users/cltbld', 10:06:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:24 INFO - 'LOGNAME': 'cltbld', 10:06:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:24 INFO - 'MOZ_NO_REMOTE': '1', 10:06:24 INFO - 'NO_EM_RESTART': '1', 10:06:24 INFO - 'PAGER': '/bin/cat', 10:06:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:24 INFO - 'PWD': '/builds/slave/test', 10:06:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:24 INFO - 'SHELL': '/bin/bash', 10:06:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eav41kw1aE/Listeners', 10:06:24 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 10:06:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:24 INFO - 'USER': 'cltbld', 10:06:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:24 INFO - 'XPC_FLAGS': '0x0', 10:06:24 INFO - 'XPC_SERVICE_NAME': '0', 10:06:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:06:24 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-XqLSjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:06:24 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-Z9OR9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:06:24 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-xPErOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:06:24 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-wEIpOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:06:25 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-Xqf7nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:06:25 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-pR5KLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:06:25 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-lsg4pK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:06:25 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-0kyIxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:06:25 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-udEqw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:06:25 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-OfJuge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:06:25 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-GoUUVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:06:25 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-Uphv9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:06:25 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-wXO3Mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:06:26 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-Oo4FVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:26 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-MzXRgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:06:26 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-IU0ub1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:06:26 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-oIkhAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:06:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:06:26 INFO - Running setup.py install for manifestparser 10:06:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:06:26 INFO - Running setup.py install for mozcrash 10:06:26 INFO - Running setup.py install for mozdebug 10:06:26 INFO - Running setup.py install for mozdevice 10:06:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:06:27 INFO - Running setup.py install for mozfile 10:06:27 INFO - Running setup.py install for mozhttpd 10:06:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:06:27 INFO - Running setup.py install for mozinfo 10:06:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:06:27 INFO - Running setup.py install for mozInstall 10:06:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:06:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:06:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:06:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:06:27 INFO - Running setup.py install for mozleak 10:06:27 INFO - Running setup.py install for mozlog 10:06:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:06:27 INFO - Running setup.py install for moznetwork 10:06:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:06:28 INFO - Running setup.py install for mozprocess 10:06:28 INFO - Running setup.py install for mozprofile 10:06:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:06:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:06:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:06:28 INFO - Running setup.py install for mozrunner 10:06:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:06:28 INFO - Running setup.py install for mozscreenshot 10:06:28 INFO - Running setup.py install for moztest 10:06:28 INFO - Running setup.py install for mozversion 10:06:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:06:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:06:28 INFO - Cleaning up... 10:06:28 INFO - Return code: 0 10:06:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:06: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:06:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06: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:06:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ebec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ec2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8b0493b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e47c60>, '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 0x107bdccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c05be8>, '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.mOYnclJfzA/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.Eav41kw1aE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:06:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:06:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:06:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOYnclJfzA/Render', 10:06:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:28 INFO - 'HOME': '/Users/cltbld', 10:06:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:28 INFO - 'LOGNAME': 'cltbld', 10:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:28 INFO - 'MOZ_NO_REMOTE': '1', 10:06:28 INFO - 'NO_EM_RESTART': '1', 10:06:28 INFO - 'PAGER': '/bin/cat', 10:06:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:28 INFO - 'PWD': '/builds/slave/test', 10:06:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:28 INFO - 'SHELL': '/bin/bash', 10:06:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eav41kw1aE/Listeners', 10:06:28 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 10:06:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:28 INFO - 'USER': 'cltbld', 10:06:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:28 INFO - 'XPC_FLAGS': '0x0', 10:06:28 INFO - 'XPC_SERVICE_NAME': '0', 10:06:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:06:29 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-DAVp24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:06:29 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:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:06:29 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-sm7Uf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:06:29 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:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:06:29 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-DhEz97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:06:29 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:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:06:29 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-iCvAut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:06:29 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-D0aV6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:06:29 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-gVzH1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:06:29 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-12zIED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:06:29 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-rgI6Mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:06:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:06:30 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-RcHMNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:06:30 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-GT9Adk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:06:30 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-pWtp6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:06:30 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-0l3N2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:06:30 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-oqdgYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:06:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:06:30 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-spaGDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:06:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:30 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-6vHeQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:06:30 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-dGGqLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:06:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:06:30 INFO - Running setup.py (path:/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/pip-0y6rHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:06:31 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:06:31 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:06:31 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:06:31 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:06:31 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:06:31 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:06:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:06: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:06: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:06: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:06: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:06:32 INFO - Downloading blessings-1.6.tar.gz 10:06:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:06:32 INFO - Installing collected packages: blessings 10:06:32 INFO - Running setup.py install for blessings 10:06:32 INFO - Successfully installed blessings 10:06:32 INFO - Cleaning up... 10:06:32 INFO - Return code: 0 10:06:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:06:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:06:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:06:33 INFO - Reading from file tmpfile_stdout 10:06:33 INFO - Current package versions: 10:06:33 INFO - blessings == 1.6 10:06:33 INFO - blobuploader == 1.2.4 10:06:33 INFO - docopt == 0.6.1 10:06:33 INFO - functools32 == 3.2.3-2 10:06:33 INFO - jsonschema == 2.5.1 10:06:33 INFO - manifestparser == 1.1 10:06:33 INFO - mozInstall == 1.12 10:06:33 INFO - mozcrash == 1.0 10:06:33 INFO - mozdebug == 0.1 10:06:33 INFO - mozdevice == 0.51 10:06:33 INFO - mozfile == 1.2 10:06:33 INFO - mozhttpd == 0.7 10:06:33 INFO - mozinfo == 0.9 10:06:33 INFO - mozleak == 0.1 10:06:33 INFO - mozlog == 3.4 10:06:33 INFO - moznetwork == 0.27 10:06:33 INFO - mozprocess == 0.25 10:06:33 INFO - mozprofile == 0.28 10:06:33 INFO - mozrunner == 6.13 10:06:33 INFO - mozscreenshot == 0.1 10:06:33 INFO - mozsystemmonitor == 0.3 10:06:33 INFO - moztest == 0.8 10:06:33 INFO - mozversion == 1.4 10:06:33 INFO - psutil == 3.1.1 10:06:33 INFO - requests == 1.2.3 10:06:33 INFO - wsgiref == 0.1.2 10:06:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:06:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:33 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 0x107ebec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ec2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8b0493b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e47c60>, '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 0x107bdccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c05be8>, '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.mOYnclJfzA/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.Eav41kw1aE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:06:33 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:06:33 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:06:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOYnclJfzA/Render', 10:06:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:33 INFO - 'HOME': '/Users/cltbld', 10:06:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:33 INFO - 'LOGNAME': 'cltbld', 10:06:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:33 INFO - 'MOZ_NO_REMOTE': '1', 10:06:33 INFO - 'NO_EM_RESTART': '1', 10:06:33 INFO - 'PAGER': '/bin/cat', 10:06:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:33 INFO - 'PWD': '/builds/slave/test', 10:06:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:33 INFO - 'SHELL': '/bin/bash', 10:06:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eav41kw1aE/Listeners', 10:06:33 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 10:06:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:33 INFO - 'USER': 'cltbld', 10:06:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:33 INFO - 'XPC_FLAGS': '0x0', 10:06:33 INFO - 'XPC_SERVICE_NAME': '0', 10:06:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:33 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:06:33 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:06:33 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:06:33 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:06:33 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:06:33 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:06:33 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:06:33 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:06:33 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:06:33 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:06:33 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:06:33 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:06:33 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:06:33 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:06:34 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:06:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:06:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:06:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:06:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:06:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:06:34 INFO - Installing collected packages: marionette-driver 10:06:34 INFO - Running setup.py install for marionette-driver 10:06:34 INFO - Successfully installed marionette-driver 10:06:34 INFO - Cleaning up... 10:06:35 INFO - Return code: 0 10:06:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:06:35 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:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:35 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:06:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:35 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 0x107ebec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ec2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8b0493b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e47c60>, '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 0x107bdccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c05be8>, '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.mOYnclJfzA/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.Eav41kw1aE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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:06:35 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:06:35 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:06:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOYnclJfzA/Render', 10:06:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:35 INFO - 'HOME': '/Users/cltbld', 10:06:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:35 INFO - 'LOGNAME': 'cltbld', 10:06:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:35 INFO - 'MOZ_NO_REMOTE': '1', 10:06:35 INFO - 'NO_EM_RESTART': '1', 10:06:35 INFO - 'PAGER': '/bin/cat', 10:06:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:35 INFO - 'PWD': '/builds/slave/test', 10:06:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:35 INFO - 'SHELL': '/bin/bash', 10:06:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eav41kw1aE/Listeners', 10:06:35 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 10:06:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:35 INFO - 'USER': 'cltbld', 10:06:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:35 INFO - 'XPC_FLAGS': '0x0', 10:06:35 INFO - 'XPC_SERVICE_NAME': '0', 10:06:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:06:35 INFO - Cleaning up... 10:06:35 INFO - Return code: 0 10:06:35 INFO - Running post-action listener: _resource_record_post_action 10:06:35 INFO - Running post-action listener: _start_resource_monitoring 10:06:35 INFO - Starting resource monitoring. 10:06:35 INFO - [mozharness: 2017-06-01 17:06:35.300688Z] Finished create-virtualenv step (success) 10:06:35 INFO - [mozharness: 2017-06-01 17:06:35.301724Z] Running install step. 10:06:35 INFO - Running pre-action listener: _resource_record_pre_action 10:06:35 INFO - Running main action method: install 10:06:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:06:35 INFO - Reading from file tmpfile_stdout 10:06:35 INFO - Detecting whether we're running mozinstall >=1.0... 10:06:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:06:35 INFO - Reading from file tmpfile_stdout 10:06:35 INFO - Output received: 10:06:35 INFO - Usage: mozinstall [options] installer 10:06:35 INFO - Options: 10:06:35 INFO - -h, --help show this help message and exit 10:06:35 INFO - -d DEST, --destination=DEST 10:06:35 INFO - Directory to install application into. [default: 10:06:35 INFO - "/builds/slave/test"] 10:06:35 INFO - --app=APP Application being installed. [default: firefox] 10:06:35 INFO - mkdir: /builds/slave/test/build/application 10:06:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:06:56 INFO - Reading from file tmpfile_stdout 10:06:56 INFO - Output received: 10:06:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:06:56 INFO - Running post-action listener: _resource_record_post_action 10:06:56 INFO - [mozharness: 2017-06-01 17:06:56.976868Z] Finished install step (success) 10:06:56 INFO - [mozharness: 2017-06-01 17:06:56.977004Z] Running setup-mitmproxy step. 10:06:56 INFO - Running pre-action listener: _resource_record_pre_action 10:06:56 INFO - Running main action method: setup_mitmproxy 10:06:56 INFO - Skipping: mitmproxy is not required 10:06:56 INFO - Running post-action listener: _resource_record_post_action 10:06:56 INFO - [mozharness: 2017-06-01 17:06:56.977685Z] Finished setup-mitmproxy step (success) 10:06:56 INFO - [mozharness: 2017-06-01 17:06:56.977799Z] Running run-tests step. 10:06:56 INFO - Running pre-action listener: _resource_record_pre_action 10:06:56 INFO - Running main action method: run_tests 10:06:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:06:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:06:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:06:56 INFO - 2017-06-01 10:06:56.992 screenresolution[1624:7350] starting screenresolution argv=screenresolution get 10:06:56 INFO - 2017-06-01 10:06:56.995 screenresolution[1624:7350] Display 0: 1600x1200x32@60 10:06:57 INFO - 2017-06-01 10:06:57.001 screenresolution[1625:7352] starting screenresolution argv=screenresolution list 10:06:57 INFO - Available Modes on Display 0 10:06:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:06:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:06:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:06:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:06:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:06:57 INFO - Intel Iris: 10:06:57 INFO - Chipset Model: Intel Iris 10:06:57 INFO - Type: GPU 10:06:57 INFO - Bus: Built-In 10:06:57 INFO - VRAM (Dynamic, Max): 1536 MB 10:06:57 INFO - Vendor: Intel (0x8086) 10:06:57 INFO - Device ID: 0x0a2e 10:06:57 INFO - Revision ID: 0x0009 10:06:57 INFO - Displays: 10:06:57 INFO - Display: 10:06:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:06:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:06:57 INFO - Display Serial Number: 1600x1200 60 10:06:57 INFO - Main Display: Yes 10:06:57 INFO - Mirror: Off 10:06:57 INFO - Online: Yes 10:06:57 INFO - Rotation: Supported 10:06:57 INFO - Return code: 0 10:06:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:06:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:06:57 INFO - Python 2.7.3 10:06:57 INFO - Return code: 0 10:06:57 INFO - grabbing minidump binary from tooltool 10:06:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:57 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 0x107e47c60>, '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 0x107bdccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c05be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:06:57 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:06:57 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:06:57 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:06:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:06:57 INFO - Return code: 0 10:06:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:06:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:06:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:06:57 INFO - ENV: RUST_BACKTRACE is now 1 10:06:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:06:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:06:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0310', '--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:06:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0310 --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:06:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mOYnclJfzA/Render', 10:06:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:57 INFO - 'HOME': '/Users/cltbld', 10:06:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:57 INFO - 'LOGNAME': 'cltbld', 10:06:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:06:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:57 INFO - 'MOZ_NO_REMOTE': '1', 10:06:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:06:57 INFO - 'NO_EM_RESTART': '1', 10:06:57 INFO - 'PAGER': '/bin/cat', 10:06:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:57 INFO - 'PWD': '/builds/slave/test', 10:06:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:06:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:57 INFO - 'RUST_BACKTRACE': '1', 10:06:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:06:57 INFO - 'SHELL': '/bin/bash', 10:06:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eav41kw1aE/Listeners', 10:06:57 INFO - 'TMPDIR': '/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/', 10:06:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:57 INFO - 'USER': 'cltbld', 10:06:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:57 INFO - 'XPC_FLAGS': '0x0', 10:06:57 INFO - 'XPC_SERVICE_NAME': '0', 10:06:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0310', '--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:06:57 INFO - mozversion application_buildid: 20170601082934 10:06:57 INFO - mozversion application_changeset: d8bdb186b2ca23ebb72984c16a1578947819bacf 10:06:57 INFO - mozversion application_display_name: Nightly 10:06:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:06:57 INFO - mozversion application_name: Firefox 10:06:57 INFO - mozversion application_remotingname: firefox 10:06:57 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 10:06:57 INFO - mozversion application_vendor: Mozilla 10:06:57 INFO - mozversion application_version: 55.0a1 10:06:57 INFO - mozversion platform_buildid: 20170601082934 10:06:57 INFO - mozversion platform_changeset: d8bdb186b2ca23ebb72984c16a1578947819bacf 10:06:57 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 10:06:57 INFO - mozversion platform_version: 55.0a1 10:06:57 INFO - using testdate: 1496336817 10:06:57 INFO - actual date: 1496336817 10:06:57 INFO - starting webserver on 'localhost:49222' 10:06:57 INFO - SUITE-START | Running 2 tests 10:06:57 INFO - TEST-START | dromaeo_css 10:06:57 INFO - operating with platform_type : mac_ 10:06:57 INFO - Initialising browser for dromaeo_css test... 10:06:57 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmpTbMmgo/profile 10:06:57 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 10:07:31 INFO - TEST-INFO | 1635: killed by SIGTERM 10:07:31 INFO - Browser initialized. 10:07:31 INFO - Running cycle 1/1 for dromaeo_css test... 10:07:31 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmpTbMmgo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:07:33 INFO - PID 1645 | 2017-06-01 10:07:33.645 plugin-container[1646:7933] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a43, name = 'com.apple.tsm.portname' 10:07:33 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:07:33 INFO - PID 1645 | 2017-06-01 10:07:33.646 plugin-container[1646:7933] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa017, name = 'com.apple.CFPasteboardClient' 10:07:33 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:07:33 INFO - PID 1645 | 2017-06-01 10:07:33.646 plugin-container[1646:7933] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:07:34 INFO - PID 1645 | 2017-06-01 10:07:34.145 plugin-container[1647:7980] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 10:07:34 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:07:34 INFO - PID 1645 | 2017-06-01 10:07:34.146 plugin-container[1647:7980] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 10:07:34 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:07:34 INFO - PID 1645 | 2017-06-01 10:07:34.146 plugin-container[1647:7980] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:07:43 INFO - PID 1645 | RSS: Main: 267874304 10:07:43 INFO - PID 1645 | 10:10:45 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49222/tests/dromaeo/cssquery-ext.html) 10:10:47 INFO - PID 1645 | RSS: Main: 273129472 10:10:47 INFO - PID 1645 | 10:13:49 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-ext.html (next: http://localhost:49222/tests/dromaeo/cssquery-jquery.html) 10:13:49 INFO - PID 1645 | RSS: Main: 282402816 10:13:49 INFO - PID 1645 | 10:16:51 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49222/tests/dromaeo/cssquery-mootools.html) 10:16:52 INFO - PID 1645 | RSS: Main: 281579520 10:16:52 INFO - PID 1645 | 10:19:53 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49222/tests/dromaeo/cssquery-prototype.html) 10:19:54 INFO - PID 1645 | RSS: Main: 285294592 10:19:54 INFO - PID 1645 | 10:22:57 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49222/tests/dromaeo/cssquery-yui.html) 10:22:58 INFO - PID 1645 | RSS: Main: 285405184 10:22:58 INFO - PID 1645 | 10:25:59 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-yui.html (next: http://localhost:49222/tests/dromaeo/cssquery-dojo.html) 10:26:00 INFO - PID 1645 | RSS: Main: 285413376 10:26:00 INFO - PID 1645 | 10:26:00 INFO - PID 1645 | __start_tp_report 10:26:00 INFO - PID 1645 | _x_x_mozilla_page_load 10:26:00 INFO - PID 1645 | _x_x_mozilla_page_load_details 10:26:00 INFO - PID 1645 | |i|pagename|runs| 10:26:00 INFO - PID 1645 | |0;dojo.html;2729.945401091978;3531.523244362012;3565.3404120237756;3574.8748793792224;3606.8196590170487;4616.953830461699;6096.085587161928;6102.938970610298;6123.11214873843;6139.109829074781;6115.602485838417;6118.541109416789;6137.775380989473;6172.290186628527;6190.7214175362105;4317.633001194902;4962.851114466572;5268.736563171845;5366.141417373221;5422.213779002042;5509.228707980886;5555.555555555546;5565.387807341189;5567.4432556744405;5594;5320.228566857803;5349.4650534946195;5426.647267927611;5433.782648694048;5488.259085023511;5481.643693159972;5485.862853428656;5493.835184944458;5497.285352904137;5532.585056120807;5364.168553874156;5384.838454846322;5400.054990376669;5406.8918621627345;5473.959947609981;6047.455728984369;6077.908831367533;6119.938800611982;6120.447133218794;6130.356312587159;6476.805695829133;6626.635535045619;6628.834279143011;6631.93368066318;6645.3354664533645;6713.530052896299;6717.563358381738;6736.595804251711;6750.223724271723;6754.527183097185;6136.938630613637;6145.907811382833;6217.595856269328;6219.502439804804;6231.283402408736;6703.932960670429;6705;6833.726650933907;6836.042953986446;6874.553154045071;6230.06549017651;6308.337624549448;6366.840828979312;6399.64801935898;6519.771807986793;5568.415316391721;6679.131712877295;6696.062551242732;6706.429953454011;6717.899231511531;6331.461825744771;6518.511111666644;6587.80236592903;6598;6599.307072757291;6364.840878978062;6454.806355809334;6505.21937367519;6523.836904077436;6576.4410025147445;5308.097623403954;5316.415194328621;5332.253484512172;5342.545883599938;5342.786288548415;4693.83571575;4711.45818230901;4731.077439899186;4732.313814496879;4745.264484004984;5624.184493248538;5927.9703601481715;6158.199434073542;6205.6586887720705;6227.19046523949;6400.615963042233;6664.233613134455;6709.496787740939;6718.899216511659;6726.293739157318;6219.098230756515;6234.75060997555;6243.531735119885;6244.219472565929;6271.843203919939;5670;5727.770889164387;5809.3609702932645;5821.708914554255;5946.940530594725;6804.489663275274;6883.965580172067;6897.758578449931;6899.517033807585;6929.064576282138;6735.158105236846;6747.797566073025;6748;6751.86496270062;6802.387785099365;6618.7683431081605;6715.697793599397;6832.624205668736;6862.725490980305;6946.583205007716;6473.352664733489;6537.803865884031;6602.966985165043;6618.801435956929;6624.768133115511;6714.83212919681;6730.8653826924165;6745.898811517929;6765.2896445872475;6770.695318710571;6263.467605253604;6367.90448143269;6389.329120442287;6446.419822216023;6502.349765023459;6483.870322593616;6510.023496475566;6515.934840651533;6526.934730652632;6584.604923704593;6265.530085243716;6345;6350.142730731477;6356.237251529846;6366.904496432465;6236.095766113979;6344.206974128234;6477.676116194078;6482.643454610042;6513.446357059703;6171.197744293213;6218.968905155445;6240.12638230639;6258.405451482102;6287.559870808999 10:26:00 INFO - PID 1645 | |1;ext.html;785.2932360875211;823.4277177361735;849.3757088539923;1045.6967479430966;1253.8495380554334;37922.8103859481;38542.22915541691;38589.42115868257;38601.80699096501;38784.418233726516;38122.4281635775;38420;38501.80749096251;38582.61417385825;38781;6999.475039372042;7848.529088254723;8007.079185893638;8559.358048146414;8578.18507241811;8475.279601233902;8553.529555874424;8555.700550480735;8572.828543429128;8581.056083830774;8510.74467765968;8536.146385361446;8604;8609.827803443928;8635.784105397352;8514.063453020162;8524.06235314115;8561.914380856175;8577.313814894793;8593.097724738875;8344.958275208646;8380.748577542683;8381.28759055481;8382.287505562035;8396.874046889301;30056.54915176274;37102.25795484075;37264.62735372666;37277;37287;3545.4327307630656;3584.9641503585153;3588.623194564559;3591.120175556979;3607.549056367954;30438.391232175454;30470;30545.23636909073;30551.388972220655;30623.08130756081;3061.3571150058515;3091.134482344947;3098.752099832008;3100.3489267253904;3106.021603195009;231606;233247.8337608301;251005.7449712758;293312;296823;45033;45232.3215151773;45258.77370613126;45264.547354526694;45275.54724452713;48400;48465.273020904715;48490.78773030632;48552.5144748555;49611.51165465042;14435.711285774436;14529.491467798576;14535.055221410574;14550.345234464474;14564.708705825824;11546.942265288788;11630.069594432425;11716.297022178696;11742.471588778526;11742.530298787953;13849.792253116211;13856.515021974183;13859.445622175;13869.930650346683;13984.300784960915;6234.688265586702;6265.373462653698;6295.213098362705;6295.842603934847;6299.937000629935;904.5793705926776;983.8734648827196;1005.6875777110795;1038.6312858934969;1081.7782354617284;20494.282700105425;23194.072237110326;24449.87775061149;24568.140115096245;24979.00083996656;963.5952899782109;1097.2100087936674;1106.6845948904524;1139.4132022008673;1142.2232881640564;9838.3113182078;11942.164048516694;12324.81512777309;12448.81326780099;12502.687432814253;306424;315544;318418;322794;323881;54192.45807541953;54512;54525.1821222682;54668.45331546713;54806.45193548014;1467.4423718986716;1509.5999560116577;1544.1198516845516;1549.775282584019;1558.8752899767992;22586;23672;23678.21148616213;23910.28269151928;23921.880390597937;29375.412491750474;36527.26945461129;36684.44973325456;36696.63303366932;36721.44917826181;260579;263143.68428157736;263946;265463;266530;34149;34666.48000280048;35049.77325793585;35333.823330883184;35354.64645353513;43833;43998;44106.897327565785;44252.336214957446;44281.557184427744;28320.433591327645;29344.70655293505;29671.25821854471;30583.694163058084;30859.074227773202;29792.404151917275;30391;30474.695253048074;30623.77504899779;30652.233694157825;35037.649623503436;35267.40340804879;35938.64061359353;36180.91457256286;36307.636923631486;29446.411071778017;30244.84877575598;30591.694083058883;30629.846850765603;30796;17871.910640446713;23650.053997839892;23761.64357534673;23775;24434.26697199087 10:26:00 INFO - PID 1645 | |2;jquery.html;341.61738852485223;414.0497558104151;428.58641411038343;443.49220142936326;456.0787209836131;14504.492342768006;15160;21470.141194352225;22213;22293.665595016068;20559.794402056013;21929.01319440625;23431.414214644643;23453.882730586323;23483.765162348373;6130.0804879268135;6243.188385509878;6263.279722831864;6300.275468321133;6306.400891915261;3645.8541658333634;6182.103594978726;6306.842328941767;6312.5896816707;6318.526110541728;3821.273957947995;6012;6289.779857704982;6303.55875088744;6328.08242804793;6268;6296.748130074791;6299.905501417483;6302.109367189824;6310.148130002437;5617.494425501724;5633.788735421863;5637.379888212294;5640.605157638967;5642.774289028475;20951.68572471414;23194.76805231943;27116.1865144046;27730.86134569314;27992.580111298346;2206.6800313954545;2242.461809165805;2262.1177740681146;2262.2195342606774;2262.875541845198;18032;18331.45005649833;18426.631467370713;18498.722519162224;18504.90747546267;2152.817010554105;2175.4778853075304;2218.6228341182036;2221.2336743823357;2225.9666105008278;176107;184101.15898841107;200794.99999999854;201713;203262;12489.50041998328;12514.31171285588;12591.748165036832;12872.485100596059;13317.800232996513;16164.434244801376;16400.015999040097;16475.929064610762;16493.257803398876;16495.83504164967;8862.955685221661;8955.955220223988;8960.820783584291;8987;9044.457332559936;6996.650167491605;8445.619947102396;8594;8603.655853765904;8671.569191083401;6427.517936154807;7416.703331866773;7767.223277672188;8235.752927412188;8262.545559994138;2289.1301305504135;2305.619572770474;2307.584634765725;2311.861288322706;2485.8508489490687;785.4698078796789;937.8686983822271;996.626265150554;998.9400635961865;1005.3364779245662;15239.619009524851;20287.289944852153;21083.68374474384;21111.366659000254;21542.892285538474;966.9854952175582;986.146982859821;1006.1196453103535;1013.8935411781804;1016.8068067067234;9234.445933244027;10946.726331841768;10970.616028438966;10992.900709928943;11210.775784484427;553025.4697452975;555326;563173;567862.2137786188;568012;154079;158734;159268;159653;161240;1133.2294040052843;1150.4937827355936;1166.311875993168;1166.9941650291864;1171.4377098992532;17093.23080461407;18097.185626647097;18693.81306186921;18766.061696914825;18925.526861828563;65362.673186635664;66106;66833.66583167053;68044;68085.63828723496;89779.55110224408;90739.54630227071;90942.09057909336;91208.54395727979;91396.54301728714;8340.666373344999;8362.749117526484;9966.551505182138;10057.446840423847;10079.546420410952;13255.337233138112;16941.23764430579;17834.375796847566;17913.37303194382;18005.279788808824;14542.4910128145;14601;14711.55865324042;14762;14783.482578110144;12866.35668216604;13158.868411316147;13254.933725331697;13259.867401325862;13340.933295333462;13698.698623630738;14647.19440430744;14743.705125897208;14780.408783648534;14909.329080191634;9150.679726209552;9950.651727189514;10044.648437304659;10047.899521004696;10047.899521004696;13976.231307278198;14376.353064112349;14430.783538247144;14533;14537.709245815236 10:26:00 INFO - PID 1645 | |3;mootools.html;1219.213607223341;1259.533972430201;1451.8475560066186;1456.6358410397402;1460.2625674034618;11564.017058550033;11663;12182.939085304572;12337.259764414119;12875.034372422071;10137.239707021967;11923.7615247695;12195.451204695788;12204.816927746091;12218.32799196044;1964.7936966618443;2326.7091613548264;2355.152145227725;2361.3624321433203;2363.019346971005;2454.47228845799;2469.7283298837115;2488.539620170265;2496.163785131983;2497.9750202497926;2461.7045954485397;2467.8889449974795;2486.0304481252238;2488.726240113586;2491.6013437849947;2414.5774489464275;2436.7319594844553;2443.3525115844313;2451.6077427611494;2453.5583594952814;2238.5858616156065;2260.1298500077423;2414.770596793302;2454.2882564056404;2540.136353639771;11673.591424300193;11761.764764704743;11771.470283837249;11776.882231177664;11792.528298868036;6366.968165159191;6630.900536491957;6632.602043877383;6633.900491492632;6635.369639884203;3770.5098337215986;3912.667423268997;3993.7204395692315;4252.340887162495;4267.210566045292;1208.112037652325;1213.205350495427;1219.1161407979232;1220.341015851448;1242.2174030360904;15231.695366092727;15622.140782256858;16495.092769897652;16629;16644.83355166457;4764.714117152982;5556.91664625031;5561.304836895388;5735.541156707537;5753.88492230153;12266.141370104007;12347.876521234673;12378.195417298028;12397.62807115788;12401.565945191875;6470.061841033118;6568.211814582281;6569;6577.980413035987;6580.736770529125;6020.367861374492;6096.634201947897;6125.356837532084;6134.938650613526;6218.626882387071;3638.3632864248652;4380.342948557742;4422.248217802917;4521.909561808746;4524.117797029613;1254;1270.4600544768437;1277.1800184736712;1283.0377217087184;1288.6907142285784;783.7962129846281;802.1657476224661;804.8429514216292;805.983880322402;820.429801288099;6173.9073913891325;6735.663216839139;6751.156105486814;6877.3810357067105;6965.407940325028;881.0880738435688;923.4413180026122;939.7368736753583;941.9811603767886;954.2175416158683;3978.7214894957074;4056.492938382702;4064;4145.730527515762;4162.895927601834;14708;15201.239938003055;15406.768898466533;15409;15442.691146177014;14465.421383144556;15161.241937903062;15311.61720956985;15317;15359.846401535842;7074;8507.234348908629;8628.007779105357;8665;8679.739607811776;6083.726232319645;6183.628982261079;6712.83217919556;6992.699489813626;7008.229094799466;11203.327800332007;13013.479460821462;13108.541201058299;13213.471461141831;13375.464981401024;10548.419836909045;13274.469021239429;13541.458341666617;13601.523946662215;13640.976926730733;9627.229821614394;9828.213742900407;9828.459434731158;9833.60665573369;9847.60609575609;10763.138948883914;10866.565337386415;10884.945575272073;10939.12487001022;11007.779844402907;8961.551922403723;9108.362414630912;9262.212711919563;9294.72115836526;9370.344075914893;7386.519876208029;9038;9155.725328240163;9156.404833685789;9338.486383248986;8133.389995750224;8202.810592464179;8241.423100383156;8244.464109832841;8261.586920654063;5912.70436478183;5963.463288304073;6025.758969641165;6122.142899994094;6166.321704612399;5795.652260864362;5877.441643043904;5894.440028197486;6004.81985540417;6050.394960503914 10:26:00 INFO - PID 1645 | |4;prototype.html;387.86812483755534;403.7032780906034;405.9776712280825;412.57092623671383;414.42601996235203;5213.817516386932;6127.601705889125;6310.747570097196;6342.46089082428;6374.553781235315;5297.073012222855;5636.520895723868;5782.595218334708;6139.907901381472;6177.559863853775;4557.931631025537;4562.771861406933;4567.4062371891605;4568.862934111982;4586.280144945646;3938.7833669148185;4108.219438306727;4444.244478438657;4584.7936842842155;4586.678932474728;3971.702122340836;4116.650084742801;4493.281075027996;4554.453465584134;4559.212826532144;4128.442660240859;4521.706089104214;4540.545945405466;4551.294549344857;4564.4522657281;3934.783586902719;3941.1329507508303;3947.6577963492264;3959.1685745993377;3969.543502497192;5510.8071217507195;6058.878822423527;6079.148919151322;6111.87776244477;6118.265808103011;5416.51251387377;5418.620696551204;5427.972860135714;5430;5452.59105567084;6572.535299262777;6598.637074960875;6606.174228221473;6622.1722284714415;6623.569467984614;4428.446444194476;4436.622887054604;4441.511433742286;4452.465704115527;4473.3960915276675;6722.764703235363;6843.452523798084;6862.553933994324;6876.312368763134;7001.724439557164;3986.960130398717;4462.486814016364;4494.348319493655;4496.662750293675;4523.185826551186;4597.356370108188;4599.0342028174455;4603.401557797465;4604.38846403756;4609.7695115244105;5188.584913206934;5347.037533243975;5363.410024897258;5371.865703357447;5379.273798037215;6541.542092053511;6582.243042050225;6620.933790662128;6625.602463852184;6626.241233941353;1323.7815760399426;1555.5100143454777;1559.6958593074469;1560.3836484588523;1566.670999090181;898.8943599372677;1018.1244130048152;1037.4916291017498;1048.192891473561;1059.390850261103;5289;5328.387235467971;6183.381661833871;6243.287392036326;6326.68366581669;5677.886442271131;6837.623930683761;6844.1444819397575;6862.289395688675;6870.690818913161;5680.346760122638;6240.188775459252;6243.687815609202;6259.937400625936;6275.560710750204;3537.7712041413743;3563.1626567756557;3581.4448760442174;3587.712982961357;3588.7129029677562;7119;7133.964330178316;7135.179454362711;7149.356557909813;7189.317014883578;6197.318294987548;7066.434685225169;7082.752103676346;7102.891513298559;7116.181639111465;5721.456461636138;6467.51493637979;6578.375054369827;7104.964475177694;7109.004739336394;6204.5967012144065;6268.843278918063;6674.965380366051;6917.861642767016;6918.481113916376;6747.122874026345;6785.423239024738;6787.89818152738;6794.675038367669;6794.942275975732;5751.712414379348;6311.747530098744;7015.614141222281;7065.894011589728;7176.856462870818;4249.702520823636;4468.64250859924;4475.888102797456;4489.229047600032;4497.302918047708;5855.414458554111;6062.333143354139;6663.068789414269;6787.355201255873;6791.2190098139345;5699.686517241592;6325.810225693236;6607.273199948098;6620.337966203341;6625.900611490735;5834.562407819515;6493.318201588954;6616.66916654175;6622.602643841385;6627.403533681992;5375.865603359791;5944.99777612303;6283.685815709287;6463.903041454476;6465.806025819233;6569.835754106186;6576.013597960345;6589.155036589571;6600.60396376219;6620.238672552526;5465.508104270635;6263.874722505616;6405.647689377129;6443.9355606445215;6449.6130232186215 10:26:00 INFO - PID 1645 | |5;yui.html;702.9086218791556;829.796699808547;844.852150873597;856.3662889461797;872.9258013068888;13923.860761392383;14401.63995900103;17119.229634666437;17461;17584.120793960297;14056.297185140753;15084.698306033873;15224.010439321464;15252.618684532892;15304.081755094701;992.1467537917385;999.2405771613553;1016.3596933931613;1020.1787561013381;1020.2246292817438;656.9080328753978;1008.6469735592557;1017.7201269650831;1023.462682091904;1027.1987849477382;720.0207717504217;997.130380536494;1028.7171027967331;1047.3611097230682;1048.2190767877942;845.9154084591553;1021.4331046269317;1038.2317085356858;1039.1946241662697;1041.9320196798305;769.084789100968;770.96916123355;771.9614019299013;773.168843493247;773.8413625206819;16624.33502659892;16731.749023764653;16794.16029198535;16795.66408671832;17273.913630431893;16886.577835554086;17073.658526829517;17226.397076102403;17641.7353739694;17649.823501765073;14567.490137845125;14846.1834599097;14853.48012819546;14853.777193342108;14874.479393221185;3445.63820798815;3469.8785542506143;3471.704905083071;3473.270613171237;3481.1645205150835;137471;138877;139527.30236348856;139852;139969.3001534996;12400.379981001095;13811.17132972025;14189.716205675828;14243.430262789372;14276.072055316372;25955.610665840028;26238.950441982106;27052.729472705414;27550.724492754816;27570.5864412034;7001.894971575431;7982.521048737094;8110.878336824952;8143;8151.5109093454585;3933.488646475997;3998.7200895937003;4000.7799571023875;4021.9945013746565;4025.5571887092397;3692.7415080944074;3808.5429748430365;4354.956450435455;4454.910901782011;4874.025194961022;796.4385108498494;958.3291695812959;971.9708408747607;972.2805124208177;974.8391515400021;641.9037144428373;661.8510835062073;670.9429698475684;673.5891106425076;685.4584877946467;10469.528871200815;10668.090009826985;11803.23278986863;12407.379631018413;12486.188397754117;635.269440143829;648.8086014625602;649.7401039584204;654.7413771560207;668.204836244867;5249.947500525022;5254.4220135785035;5299.49654782795;5317.7872885084935;5440.619156659075;104523.4773826126;136913;137598;142395;142694;102348;119659.60680786335;131221;132249.3387533056;132351.01473477905;81491.5925420381;109884;114319.56922799992;116730.41634791772;117479;9000.414973026733;10845;10895.182861285277;11024.062954648945;11342.546298147981;28326.858365708864;28907.855460722563;42066.789666051474;42432;43600;55972.72013639906;56255;56794.14808777789;56907.43092569021;56988.71505642611;14604.26978651042;14708.264586770832;14888.478903238334;15119.697606047597;15120.017198882037;14160.929195353958;16189.838101618832;16473.67052658916;16555.75166372481;16659.500214993568;15250.542483725507;15366.769498457308;15419.922900385875;15557.611059723598;15677.137757423452;14799;15206.695866082837;15403.229838508254;15506.767398489257;15534.22328883574;14879;15029.398824046915;15223.467178648694;15225.771613425586;15310.770338444709;8448.408611397143;11007.559697612327;11092.66721998309;11107.611233606785;11222.102231821598;11617.361045142598;12010.159288849696;12067.275963442222;12153.210041347284;12235.265884046985 10:26:00 INFO - PID 1645 | __end_tp_report 10:26:00 INFO - PID 1645 | __start_cc_report 10:26:00 INFO - PID 1645 | _x_x_mozilla_cycle_collect,446 10:26:00 INFO - PID 1645 | __end_cc_report 10:26:00 INFO - PID 1645 | __startTimestamp1496337960059__endTimestamp 10:26:00 INFO - PID 1645 | 10:26:00 INFO - PID 1645 | ------- Summary: start ------- 10:26:00 INFO - PID 1645 | Number of tests: 6 10:26:00 INFO - PID 1645 | 10:26:00 INFO - PID 1645 | [#0] dojo.html Cycles:165 Average:6090.31 Median:6265.53 stddev:745.82 (11.9%) stddev-sans-first:699.98 10:26:00 INFO - PID 1645 | Values: 2729.9 3531.5 3565.3 3574.9 3606.8 4617.0 6096.1 6102.9 6123.1 6139.1 6115.6 6118.5 6137.8 6172.3 6190.7 4317.6 4962.9 5268.7 5366.1 5422.2 5509.2 5555.6 5565.4 5567.4 5594.0 5320.2 5349.5 5426.6 5433.8 5488.3 5481.6 5485.9 5493.8 5497.3 5532.6 5364.2 5384.8 5400.1 5406.9 5474.0 6047.5 6077.9 6119.9 6120.4 6130.4 6476.8 6626.6 6628.8 6631.9 6645.3 6713.5 6717.6 6736.6 6750.2 6754.5 6136.9 6145.9 6217.6 6219.5 6231.3 6703.9 6705.0 6833.7 6836.0 6874.6 6230.1 6308.3 6366.8 6399.6 6519.8 5568.4 6679.1 6696.1 6706.4 6717.9 6331.5 6518.5 6587.8 6598.0 6599.3 6364.8 6454.8 6505.2 6523.8 6576.4 5308.1 5316.4 5332.3 5342.5 5342.8 4693.8 4711.5 4731.1 4732.3 4745.3 5624.2 5928.0 6158.2 6205.7 6227.2 6400.6 6664.2 6709.5 6718.9 6726.3 6219.1 6234.8 6243.5 6244.2 6271.8 5670.0 5727.8 5809.4 5821.7 5946.9 6804.5 6884.0 6897.8 6899.5 6929.1 6735.2 6747.8 6748.0 6751.9 6802.4 6618.8 6715.7 6832.6 6862.7 6946.6 6473.4 6537.8 6603.0 6618.8 6624.8 6714.8 6730.9 6745.9 6765.3 6770.7 6263.5 6367.9 6389.3 6446.4 6502.3 6483.9 6510.0 6515.9 6526.9 6584.6 6265.5 6345.0 6350.1 6356.2 6366.9 6236.1 6344.2 6477.7 6482.6 6513.4 6171.2 6219.0 6240.1 6258.4 6287.6 10:26:00 INFO - PID 1645 | 10:26:00 INFO - PID 1645 | [#1] ext.html Cycles:180 Average:43222.08 Median:34407.74 stddev:73986.17 (215.0%) stddev-sans-first:74125.11 10:26:00 INFO - PID 1645 | Values: 785.3 823.4 849.4 1045.7 1253.8 37922.8 38542.2 38589.4 38601.8 38784.4 38122.4 38420.0 38501.8 38582.6 38781.0 6999.5 7848.5 8007.1 8559.4 8578.2 8475.3 8553.5 8555.7 8572.8 8581.1 8510.7 8536.1 8604.0 8609.8 8635.8 8514.1 8524.1 8561.9 8577.3 8593.1 8345.0 8380.7 8381.3 8382.3 8396.9 30056.5 37102.3 37264.6 37277.0 37287.0 3545.4 3585.0 3588.6 3591.1 3607.5 30438.4 30470.0 30545.2 30551.4 30623.1 3061.4 3091.1 3098.8 3100.3 3106.0 231606.0 233247.8 251005.7 293312.0 296823.0 45033.0 45232.3 45258.8 45264.5 45275.5 48400.0 48465.3 48490.8 48552.5 49611.5 14435.7 14529.5 14535.1 14550.3 14564.7 11546.9 11630.1 11716.3 11742.5 11742.5 13849.8 13856.5 13859.4 13869.9 13984.3 6234.7 6265.4 6295.2 6295.8 6299.9 904.6 983.9 1005.7 1038.6 1081.8 20494.3 23194.1 24449.9 24568.1 24979.0 963.6 1097.2 1106.7 1139.4 1142.2 9838.3 11942.2 12324.8 12448.8 12502.7 306424.0 315544.0 318418.0 322794.0 323881.0 54192.5 54512.0 54525.2 54668.5 54806.5 1467.4 1509.6 1544.1 1549.8 1558.9 22586.0 23672.0 23678.2 23910.3 23921.9 29375.4 36527.3 36684.4 36696.6 36721.4 260579.0 263143.7 263946.0 265463.0 266530.0 34149.0 34666.5 35049.8 35333.8 35354.6 43833.0 43998.0 44106.9 44252.3 44281.6 28320.4 29344.7 29671.3 30583.7 30859.1 29792.4 30391.0 30474.7 30623.8 30652.2 35037.6 35267.4 35938.6 36180.9 36307.6 29446.4 30244.8 30591.7 30629.8 30796.0 17871.9 23650.1 23761.6 23775.0 24434.3 10:26:00 INFO - PID 1645 | 10:26:00 INFO - PID 1645 | [#2] jquery.html Cycles:180 Average:38667.86 Median:12259.82 stddev:97688.65 (796.8%) stddev-sans-first:97920.31 10:26:00 INFO - PID 1645 | Values: 341.6 414.0 428.6 443.5 456.1 14504.5 15160.0 21470.1 22213.0 22293.7 20559.8 21929.0 23431.4 23453.9 23483.8 6130.1 6243.2 6263.3 6300.3 6306.4 3645.9 6182.1 6306.8 6312.6 6318.5 3821.3 6012.0 6289.8 6303.6 6328.1 6268.0 6296.7 6299.9 6302.1 6310.1 5617.5 5633.8 5637.4 5640.6 5642.8 20951.7 23194.8 27116.2 27730.9 27992.6 2206.7 2242.5 2262.1 2262.2 2262.9 18032.0 18331.5 18426.6 18498.7 18504.9 2152.8 2175.5 2218.6 2221.2 2226.0 176107.0 184101.2 200795.0 201713.0 203262.0 12489.5 12514.3 12591.7 12872.5 13317.8 16164.4 16400.0 16475.9 16493.3 16495.8 8863.0 8956.0 8960.8 8987.0 9044.5 6996.7 8445.6 8594.0 8603.7 8671.6 6427.5 7416.7 7767.2 8235.8 8262.5 2289.1 2305.6 2307.6 2311.9 2485.9 785.5 937.9 996.6 998.9 1005.3 15239.6 20287.3 21083.7 21111.4 21542.9 967.0 986.1 1006.1 1013.9 1016.8 9234.4 10946.7 10970.6 10992.9 11210.8 553025.5 555326.0 563173.0 567862.2 568012.0 154079.0 158734.0 159268.0 159653.0 161240.0 1133.2 1150.5 1166.3 1167.0 1171.4 17093.2 18097.2 18693.8 18766.1 18925.5 65362.7 66106.0 66833.7 68044.0 68085.6 89779.6 90739.5 90942.1 91208.5 91396.5 8340.7 8362.7 9966.6 10057.4 10079.5 13255.3 16941.2 17834.4 17913.4 18005.3 14542.5 14601.0 14711.6 14762.0 14783.5 12866.4 13158.9 13254.9 13259.9 13340.9 13698.7 14647.2 14743.7 14780.4 14909.3 9150.7 9950.7 10044.6 10047.9 10047.9 13976.2 14376.4 14430.8 14533.0 14537.7 10:26:00 INFO - PID 1645 | 10:26:00 INFO - PID 1645 | [#3] mootools.html Cycles:180 Average:7095.66 Median:3704.44 stddev:4526.10 (122.2%) stddev-sans-first:4517.26 10:26:00 INFO - PID 1645 | Values: 1219.2 1259.5 1451.8 1456.6 1460.3 11564.0 11663.0 12182.9 12337.3 12875.0 10137.2 11923.8 12195.5 12204.8 12218.3 1964.8 2326.7 2355.2 2361.4 2363.0 2454.5 2469.7 2488.5 2496.2 2498.0 2461.7 2467.9 2486.0 2488.7 2491.6 2414.6 2436.7 2443.4 2451.6 2453.6 2238.6 2260.1 2414.8 2454.3 2540.1 11673.6 11761.8 11771.5 11776.9 11792.5 6367.0 6630.9 6632.6 6633.9 6635.4 3770.5 3912.7 3993.7 4252.3 4267.2 1208.1 1213.2 1219.1 1220.3 1242.2 15231.7 15622.1 16495.1 16629.0 16644.8 4764.7 5556.9 5561.3 5735.5 5753.9 12266.1 12347.9 12378.2 12397.6 12401.6 6470.1 6568.2 6569.0 6578.0 6580.7 6020.4 6096.6 6125.4 6134.9 6218.6 3638.4 4380.3 4422.2 4521.9 4524.1 1254.0 1270.5 1277.2 1283.0 1288.7 783.8 802.2 804.8 806.0 820.4 6173.9 6735.7 6751.2 6877.4 6965.4 881.1 923.4 939.7 942.0 954.2 3978.7 4056.5 4064.0 4145.7 4162.9 14708.0 15201.2 15406.8 15409.0 15442.7 14465.4 15161.2 15311.6 15317.0 15359.8 7074.0 8507.2 8628.0 8665.0 8679.7 6083.7 6183.6 6712.8 6992.7 7008.2 11203.3 13013.5 13108.5 13213.5 13375.5 10548.4 13274.5 13541.5 13601.5 13641.0 9627.2 9828.2 9828.5 9833.6 9847.6 10763.1 10866.6 10884.9 10939.1 11007.8 8961.6 9108.4 9262.2 9294.7 9370.3 7386.5 9038.0 9155.7 9156.4 9338.5 8133.4 8202.8 8241.4 8244.5 8261.6 5912.7 5963.5 6025.8 6122.1 6166.3 5795.7 5877.4 5894.4 6004.8 6050.4 10:26:00 INFO - PID 1645 | 10:26:00 INFO - PID 1645 | [#4] prototype.html Cycles:180 Average:5338.73 Median:6001.94 stddev:1672.22 (27.9%) stddev-sans-first:1635.10 10:26:00 INFO - PID 1645 | Values: 387.9 403.7 406.0 412.6 414.4 5213.8 6127.6 6310.7 6342.5 6374.6 5297.1 5636.5 5782.6 6139.9 6177.6 4557.9 4562.8 4567.4 4568.9 4586.3 3938.8 4108.2 4444.2 4584.8 4586.7 3971.7 4116.7 4493.3 4554.5 4559.2 4128.4 4521.7 4540.5 4551.3 4564.5 3934.8 3941.1 3947.7 3959.2 3969.5 5510.8 6058.9 6079.1 6111.9 6118.3 5416.5 5418.6 5428.0 5430.0 5452.6 6572.5 6598.6 6606.2 6622.2 6623.6 4428.4 4436.6 4441.5 4452.5 4473.4 6722.8 6843.5 6862.6 6876.3 7001.7 3987.0 4462.5 4494.3 4496.7 4523.2 4597.4 4599.0 4603.4 4604.4 4609.8 5188.6 5347.0 5363.4 5371.9 5379.3 6541.5 6582.2 6620.9 6625.6 6626.2 1323.8 1555.5 1559.7 1560.4 1566.7 898.9 1018.1 1037.5 1048.2 1059.4 5289.0 5328.4 6183.4 6243.3 6326.7 5677.9 6837.6 6844.1 6862.3 6870.7 5680.3 6240.2 6243.7 6259.9 6275.6 3537.8 3563.2 3581.4 3587.7 3588.7 7119.0 7134.0 7135.2 7149.4 7189.3 6197.3 7066.4 7082.8 7102.9 7116.2 5721.5 6467.5 6578.4 7105.0 7109.0 6204.6 6268.8 6675.0 6917.9 6918.5 6747.1 6785.4 6787.9 6794.7 6794.9 5751.7 6311.7 7015.6 7065.9 7176.9 4249.7 4468.6 4475.9 4489.2 4497.3 5855.4 6062.3 6663.1 6787.4 6791.2 5699.7 6325.8 6607.3 6620.3 6625.9 5834.6 6493.3 6616.7 6622.6 6627.4 5375.9 5945.0 6283.7 6463.9 6465.8 6569.8 6576.0 6589.2 6600.6 6620.2 5465.5 6263.9 6405.6 6443.9 6449.6 10:26:00 INFO - PID 1645 | 10:26:00 INFO - PID 1645 | [#5] yui.html Cycles:180 Average:24223.74 Median:16514.71 stddev:38106.55 (230.7%) stddev-sans-first:38172.52 10:26:00 INFO - PID 1645 | Values: 702.9 829.8 844.9 856.4 872.9 13923.9 14401.6 17119.2 17461.0 17584.1 14056.3 15084.7 15224.0 15252.6 15304.1 992.1 999.2 1016.4 1020.2 1020.2 656.9 1008.6 1017.7 1023.5 1027.2 720.0 997.1 1028.7 1047.4 1048.2 845.9 1021.4 1038.2 1039.2 1041.9 769.1 771.0 772.0 773.2 773.8 16624.3 16731.7 16794.2 16795.7 17273.9 16886.6 17073.7 17226.4 17641.7 17649.8 14567.5 14846.2 14853.5 14853.8 14874.5 3445.6 3469.9 3471.7 3473.3 3481.2 137471.0 138877.0 139527.3 139852.0 139969.3 12400.4 13811.2 14189.7 14243.4 14276.1 25955.6 26239.0 27052.7 27550.7 27570.6 7001.9 7982.5 8110.9 8143.0 8151.5 3933.5 3998.7 4000.8 4022.0 4025.6 3692.7 3808.5 4355.0 4454.9 4874.0 796.4 958.3 972.0 972.3 974.8 641.9 661.9 670.9 673.6 685.5 10469.5 10668.1 11803.2 12407.4 12486.2 635.3 648.8 649.7 654.7 668.2 5249.9 5254.4 5299.5 5317.8 5440.6 104523.5 136913.0 137598.0 142395.0 142694.0 102348.0 119659.6 131221.0 132249.3 132351.0 81491.6 109884.0 114319.6 116730.4 117479.0 9000.4 10845.0 10895.2 11024.1 11342.5 28326.9 28907.9 42066.8 42432.0 43600.0 55972.7 56255.0 56794.1 56907.4 56988.7 14604.3 14708.3 14888.5 15119.7 15120.0 14160.9 16189.8 16473.7 16555.8 16659.5 15250.5 15366.8 15419.9 15557.6 15677.1 14799.0 15206.7 15403.2 15506.8 15534.2 14879.0 15029.4 15223.5 15225.8 15310.8 8448.4 11007.6 11092.7 11107.6 11222.1 11617.4 12010.2 12067.3 12153.2 12235.3 10:26:00 INFO - PID 1645 | -------- Summary: end -------- 10:26:00 INFO - PID 1645 | 10:26:00 INFO - TEST-INFO | 1645: exit 0 10:26:00 INFO - TEST-OK | dromaeo_css | took 1143005ms 10:26:00 INFO - TEST-START | kraken 10:26:00 INFO - operating with platform_type : mac_ 10:26:00 INFO - Initialising browser for kraken test... 10:26:00 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmpuUY0S7/profile 10:26:00 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 10:26:34 INFO - TEST-INFO | 1654: killed by SIGTERM 10:26:34 INFO - Browser initialized. 10:26:34 INFO - Running cycle 1/1 for kraken test... 10:26:34 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/T/tmpuUY0S7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:26:36 INFO - PID 1658 | 2017-06-01 10:26:36.220 plugin-container[1659:10584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983b, name = 'com.apple.tsm.portname' 10:26:36 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:26:36 INFO - PID 1658 | 2017-06-01 10:26:36.221 plugin-container[1659:10584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x483f, name = 'com.apple.CFPasteboardClient' 10:26:36 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:26:36 INFO - PID 1658 | 2017-06-01 10:26:36.221 plugin-container[1659:10584] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:26:36 INFO - PID 1658 | 2017-06-01 10:26:36.989 plugin-container[1660:10633] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b53, name = 'com.apple.tsm.portname' 10:26:36 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:26:36 INFO - PID 1658 | 2017-06-01 10:26:36.989 plugin-container[1660:10633] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450f, name = 'com.apple.CFPasteboardClient' 10:26:36 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:26:36 INFO - PID 1658 | 2017-06-01 10:26:36.989 plugin-container[1660:10633] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:26:46 INFO - PID 1658 | RSS: Main: 259715072 10:26:46 INFO - PID 1658 | 10:26:49 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection) 10:26:50 INFO - PID 1658 | RSS: Main: 270852096 10:26:50 INFO - PID 1658 | 10:26:52 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-dft) 10:26:53 INFO - PID 1658 | RSS: Main: 278347776 10:26:53 INFO - PID 1658 | 10:26:56 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-fft) 10:26:57 INFO - PID 1658 | RSS: Main: 281092096 10:26:57 INFO - PID 1658 | 10:26:59 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator) 10:26:59 INFO - PID 1658 | RSS: Main: 283348992 10:26:59 INFO - PID 1658 | 10:27:01 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:27:02 INFO - PID 1658 | RSS: Main: 284012544 10:27:02 INFO - PID 1658 | 10:27:04 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom) 10:27:05 INFO - PID 1658 | RSS: Main: 288391168 10:27:05 INFO - PID 1658 | 10:27:08 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate) 10:27:09 INFO - PID 1658 | RSS: Main: 291004416 10:27:09 INFO - PID 1658 | 10:27:12 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial) 10:27:13 INFO - PID 1658 | RSS: Main: 290455552 10:27:13 INFO - PID 1658 | 10:27:14 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:27:15 INFO - PID 1658 | RSS: Main: 289898496 10:27:15 INFO - PID 1658 | 10:27:16 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:27:17 INFO - PID 1658 | RSS: Main: 289718272 10:27:17 INFO - PID 1658 | 10:27:19 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:27:20 INFO - PID 1658 | RSS: Main: 289624064 10:27:20 INFO - PID 1658 | 10:27:23 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:27:24 INFO - PID 1658 | RSS: Main: 289271808 10:27:24 INFO - PID 1658 | 10:27:27 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:27:27 INFO - PID 1658 | RSS: Main: 289505280 10:27:27 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49222/tests/kraken/driver.html?testName=ai-astar) 10:27:29 INFO - PID 1658 | RSS: Main: 291184640 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | __start_tp_report 10:27:29 INFO - PID 1658 | _x_x_mozilla_page_load 10:27:29 INFO - PID 1658 | _x_x_mozilla_page_load_details 10:27:29 INFO - PID 1658 | |i|pagename|runs| 10:27:29 INFO - PID 1658 | |0;ai-astar;110;169;168;167;113;114;115;116;113;113 10:27:29 INFO - PID 1658 | |1;audio-beat-detection;136;112;112;150;112;107;110;114;132;108 10:27:29 INFO - PID 1658 | |2;audio-dft;200;195;199;207;207;215;222;200;198;198 10:27:29 INFO - PID 1658 | |3;audio-fft;75;57;57;87;59;61;58;59;76;63 10:27:29 INFO - PID 1658 | |4;audio-oscillator;76;70;85;73;67;68;87;72;70;96 10:27:29 INFO - PID 1658 | |5;imaging-gaussian-blur;89;91;89;88;89;92;88;88;89;96 10:27:29 INFO - PID 1658 | |6;imaging-darkroom;97;97;96;97;98;96;97;96;96;96 10:27:29 INFO - PID 1658 | |7;imaging-desaturate;124;95;144;81;141;143;90;89;91;91 10:27:29 INFO - PID 1658 | |8;json-parse-financial;53;53;51;55;52;51;52;53;50;54 10:27:29 INFO - PID 1658 | |9;json-stringify-tinderbox;56;57;63;57;64;58;55;55;63;72 10:27:29 INFO - PID 1658 | |10;stanford-crypto-aes;87;87;85;87;88;85;88;91;149;86 10:27:29 INFO - PID 1658 | |11;stanford-crypto-ccm;136;142;152;145;138;154;142;151;146;154 10:27:29 INFO - PID 1658 | |12;stanford-crypto-pbkdf2;183;225;230;187;180;187;186;187;185;186 10:27:29 INFO - PID 1658 | |13;stanford-crypto-sha256-iterative;69;68;71;97;97;95;78;84;68;74 10:27:29 INFO - PID 1658 | __end_tp_report 10:27:29 INFO - PID 1658 | __start_cc_report 10:27:29 INFO - PID 1658 | _x_x_mozilla_cycle_collect,1183 10:27:29 INFO - PID 1658 | __end_cc_report 10:27:29 INFO - PID 1658 | __startTimestamp1496338049875__endTimestamp 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | ------- Summary: start ------- 10:27:29 INFO - PID 1658 | Number of tests: 14 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | [#0] ai-astar Cycles:10 Average:129.80 Median:115.50 stddev:26.41 (22.9%) stddev-sans-first:27.02 10:27:29 INFO - PID 1658 | Values: 110.0 169.0 168.0 167.0 113.0 114.0 115.0 116.0 113.0 113.0 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | [#1] audio-beat-detection Cycles:10 Average:119.30 Median:113.00 stddev:14.67 (13.0%) stddev-sans-first:14.26 10:27:29 INFO - PID 1658 | Values: 136.0 112.0 112.0 150.0 112.0 107.0 110.0 114.0 132.0 108.0 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | [#2] audio-dft Cycles:10 Average:204.10 Median:203.50 stddev:8.65 (4.2%) stddev-sans-first:9.04 10:27:29 INFO - PID 1658 | Values: 200.0 195.0 199.0 207.0 207.0 215.0 222.0 200.0 198.0 198.0 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | [#3] audio-fft Cycles:10 Average:65.20 Median:62.00 stddev:10.40 (16.8%) stddev-sans-first:10.41 10:27:29 INFO - PID 1658 | Values: 75.0 57.0 57.0 87.0 59.0 61.0 58.0 59.0 76.0 63.0 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | [#4] audio-oscillator Cycles:10 Average:76.40 Median:74.50 stddev:9.67 (13.0%) stddev-sans-first:10.26 10:27:29 INFO - PID 1658 | Values: 76.0 70.0 85.0 73.0 67.0 68.0 87.0 72.0 70.0 96.0 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:89.00 stddev:2.51 (2.8%) stddev-sans-first:2.65 10:27:29 INFO - PID 1658 | Values: 89.0 91.0 89.0 88.0 89.0 92.0 88.0 88.0 89.0 96.0 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | [#6] imaging-darkroom Cycles:10 Average:96.60 Median:97.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 10:27:29 INFO - PID 1658 | Values: 97.0 97.0 96.0 97.0 98.0 96.0 97.0 96.0 96.0 96.0 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | [#7] imaging-desaturate Cycles:10 Average:108.90 Median:89.50 stddev:25.86 (28.9%) stddev-sans-first:26.85 10:27:29 INFO - PID 1658 | Values: 124.0 95.0 144.0 81.0 141.0 143.0 90.0 89.0 91.0 91.0 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:53.00 stddev:1.51 (2.8%) stddev-sans-first:1.58 10:27:29 INFO - PID 1658 | Values: 53.0 53.0 51.0 55.0 52.0 51.0 52.0 53.0 50.0 54.0 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.50 stddev:5.44 (9.0%) stddev-sans-first:5.57 10:27:29 INFO - PID 1658 | Values: 56.0 57.0 63.0 57.0 64.0 58.0 55.0 55.0 63.0 72.0 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | [#10] stanford-crypto-aes Cycles:10 Average:93.30 Median:87.00 stddev:19.65 (22.6%) stddev-sans-first:20.71 10:27:29 INFO - PID 1658 | Values: 87.0 87.0 85.0 87.0 88.0 85.0 88.0 91.0 149.0 86.0 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:148.50 stddev:6.55 (4.4%) stddev-sans-first:5.86 10:27:29 INFO - PID 1658 | Values: 136.0 142.0 152.0 145.0 138.0 154.0 142.0 151.0 146.0 154.0 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.60 Median:187.00 stddev:18.04 (9.6%) stddev-sans-first:18.72 10:27:29 INFO - PID 1658 | Values: 183.0 225.0 230.0 187.0 180.0 187.0 186.0 187.0 185.0 186.0 10:27:29 INFO - PID 1658 | 10:27:29 INFO - PID 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.10 Median:81.00 stddev:12.24 (15.1%) stddev-sans-first:12.31 10:27:29 INFO - PID 1658 | Values: 69.0 68.0 71.0 97.0 97.0 95.0 78.0 84.0 68.0 74.0 10:27:29 INFO - PID 1658 | -------- Summary: end -------- 10:27:29 INFO - PID 1658 | 10:27:30 INFO - TEST-INFO | 1658: exit 0 10:27:30 INFO - TEST-OK | kraken | took 90060ms 10:27:30 INFO - SUITE-END | took 1233s 10:27:31 INFO - Completed test suite (00:20:33) 10:27:31 INFO - javascript benchmark 10:27:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7910.656457088812, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2729.945401091978, 3531.523244362012, 3565.3404120237756, 3574.8748793792224, 3606.8196590170487, 4616.953830461699, 6096.085587161928, 6102.938970610298, 6123.11214873843, 6139.109829074781, 6115.602485838417, 6118.541109416789, 6137.775380989473, 6172.290186628527, 6190.7214175362105, 4317.633001194902, 4962.851114466572, 5268.736563171845, 5366.141417373221, 5422.213779002042, 5509.228707980886, 5555.555555555546, 5565.387807341189, 5567.4432556744405, 5594.0, 5320.228566857803, 5349.4650534946195, 5426.647267927611, 5433.782648694048, 5488.259085023511, 5481.643693159972, 5485.862853428656, 5493.835184944458, 5497.285352904137, 5532.585056120807, 5364.168553874156, 5384.838454846322, 5400.054990376669, 5406.8918621627345, 5473.959947609981, 6047.455728984369, 6077.908831367533, 6119.938800611982, 6120.447133218794, 6130.356312587159, 6476.805695829133, 6626.635535045619, 6628.834279143011, 6631.93368066318, 6645.3354664533645, 6713.530052896299, 6717.563358381738, 6736.595804251711, 6750.223724271723, 6754.527183097185, 6136.938630613637, 6145.907811382833, 6217.595856269328, 6219.502439804804, 6231.283402408736, 6703.932960670429, 6705.0, 6833.726650933907, 6836.042953986446, 6874.553154045071, 6230.06549017651, 6308.337624549448, 6366.840828979312, 6399.64801935898, 6519.771807986793, 5568.415316391721, 6679.131712877295, 6696.062551242732, 6706.429953454011, 6717.899231511531, 6331.461825744771, 6518.511111666644, 6587.80236592903, 6598.0, 6599.307072757291, 6364.840878978062, 6454.806355809334, 6505.21937367519, 6523.836904077436, 6576.4410025147445, 5308.097623403954, 5316.415194328621, 5332.253484512172, 5342.545883599938, 5342.786288548415, 4693.83571575, 4711.45818230901, 4731.077439899186, 4732.313814496879, 4745.264484004984, 5624.184493248538, 5927.9703601481715, 6158.199434073542, 6205.6586887720705, 6227.19046523949, 6400.615963042233, 6664.233613134455, 6709.496787740939, 6718.899216511659, 6726.293739157318, 6219.098230756515, 6234.75060997555, 6243.531735119885, 6244.219472565929, 6271.843203919939, 5670.0, 5727.770889164387, 5809.3609702932645, 5821.708914554255, 5946.940530594725, 6804.489663275274, 6883.965580172067, 6897.758578449931, 6899.517033807585, 6929.064576282138, 6735.158105236846, 6747.797566073025, 6748.0, 6751.86496270062, 6802.387785099365, 6618.7683431081605, 6715.697793599397, 6832.624205668736, 6862.725490980305, 6946.583205007716, 6473.352664733489, 6537.803865884031, 6602.966985165043, 6618.801435956929, 6624.768133115511, 6714.83212919681, 6730.8653826924165, 6745.898811517929, 6765.2896445872475, 6770.695318710571, 6263.467605253604, 6367.90448143269, 6389.329120442287, 6446.419822216023, 6502.349765023459, 6483.870322593616, 6510.023496475566, 6515.934840651533, 6526.934730652632, 6584.604923704593, 6265.530085243716, 6345.0, 6350.142730731477, 6356.237251529846, 6366.904496432465, 6236.095766113979, 6344.206974128234, 6477.676116194078, 6482.643454610042, 6513.446357059703, 6171.197744293213, 6218.968905155445, 6240.12638230639, 6258.405451482102, 6287.559870808999], "value": 6038.715817810489, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [785.2932360875211, 823.4277177361735, 849.3757088539923, 1045.6967479430966, 1253.8495380554334, 37922.8103859481, 38542.22915541691, 38589.42115868257, 38601.80699096501, 38784.418233726516, 38122.4281635775, 38420.0, 38501.80749096251, 38582.61417385825, 38781.0, 6999.475039372042, 7848.529088254723, 8007.079185893638, 8559.358048146414, 8578.18507241811, 8475.279601233902, 8553.529555874424, 8555.700550480735, 8572.828543429128, 8581.056083830774, 8510.74467765968, 8536.146385361446, 8604.0, 8609.827803443928, 8635.784105397352, 8514.063453020162, 8524.06235314115, 8561.914380856175, 8577.313814894793, 8593.097724738875, 8344.958275208646, 8380.748577542683, 8381.28759055481, 8382.287505562035, 8396.874046889301, 30056.54915176274, 37102.25795484075, 37264.62735372666, 37277.0, 37287.0, 3545.4327307630656, 3584.9641503585153, 3588.623194564559, 3591.120175556979, 3607.549056367954, 30438.391232175454, 30470.0, 30545.23636909073, 30551.388972220655, 30623.08130756081, 3061.3571150058515, 3091.134482344947, 3098.752099832008, 3100.3489267253904, 3106.021603195009, 231606.0, 233247.8337608301, 251005.7449712758, 293312.0, 296823.0, 45033.0, 45232.3215151773, 45258.77370613126, 45264.547354526694, 45275.54724452713, 48400.0, 48465.273020904715, 48490.78773030632, 48552.5144748555, 49611.51165465042, 14435.711285774436, 14529.491467798576, 14535.055221410574, 14550.345234464474, 14564.708705825824, 11546.942265288788, 11630.069594432425, 11716.297022178696, 11742.471588778526, 11742.530298787953, 13849.792253116211, 13856.515021974183, 13859.445622175, 13869.930650346683, 13984.300784960915, 6234.688265586702, 6265.373462653698, 6295.213098362705, 6295.842603934847, 6299.937000629935, 904.5793705926776, 983.8734648827196, 1005.6875777110795, 1038.6312858934969, 1081.7782354617284, 20494.282700105425, 23194.072237110326, 24449.87775061149, 24568.140115096245, 24979.00083996656, 963.5952899782109, 1097.2100087936674, 1106.6845948904524, 1139.4132022008673, 1142.2232881640564, 9838.3113182078, 11942.164048516694, 12324.81512777309, 12448.81326780099, 12502.687432814253, 306424.0, 315544.0, 318418.0, 322794.0, 323881.0, 54192.45807541953, 54512.0, 54525.1821222682, 54668.45331546713, 54806.45193548014, 1467.4423718986716, 1509.5999560116577, 1544.1198516845516, 1549.775282584019, 1558.8752899767992, 22586.0, 23672.0, 23678.21148616213, 23910.28269151928, 23921.880390597937, 29375.412491750474, 36527.26945461129, 36684.44973325456, 36696.63303366932, 36721.44917826181, 260579.0, 263143.68428157736, 263946.0, 265463.0, 266530.0, 34149.0, 34666.48000280048, 35049.77325793585, 35333.823330883184, 35354.64645353513, 43833.0, 43998.0, 44106.897327565785, 44252.336214957446, 44281.557184427744, 28320.433591327645, 29344.70655293505, 29671.25821854471, 30583.694163058084, 30859.074227773202, 29792.404151917275, 30391.0, 30474.695253048074, 30623.77504899779, 30652.233694157825, 35037.649623503436, 35267.40340804879, 35938.64061359353, 36180.91457256286, 36307.636923631486, 29446.411071778017, 30244.84877575598, 30591.694083058883, 30629.846850765603, 30796.0, 17871.910640446713, 23650.053997839892, 23761.64357534673, 23775.0, 24434.26697199087], "value": 17296.209020486727, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.61738852485223, 414.0497558104151, 428.58641411038343, 443.49220142936326, 456.0787209836131, 14504.492342768006, 15160.0, 21470.141194352225, 22213.0, 22293.665595016068, 20559.794402056013, 21929.01319440625, 23431.414214644643, 23453.882730586323, 23483.765162348373, 6130.0804879268135, 6243.188385509878, 6263.279722831864, 6300.275468321133, 6306.400891915261, 3645.8541658333634, 6182.103594978726, 6306.842328941767, 6312.5896816707, 6318.526110541728, 3821.273957947995, 6012.0, 6289.779857704982, 6303.55875088744, 6328.08242804793, 6268.0, 6296.748130074791, 6299.905501417483, 6302.109367189824, 6310.148130002437, 5617.494425501724, 5633.788735421863, 5637.379888212294, 5640.605157638967, 5642.774289028475, 20951.68572471414, 23194.76805231943, 27116.1865144046, 27730.86134569314, 27992.580111298346, 2206.6800313954545, 2242.461809165805, 2262.1177740681146, 2262.2195342606774, 2262.875541845198, 18032.0, 18331.45005649833, 18426.631467370713, 18498.722519162224, 18504.90747546267, 2152.817010554105, 2175.4778853075304, 2218.6228341182036, 2221.2336743823357, 2225.9666105008278, 176107.0, 184101.15898841107, 200794.99999999854, 201713.0, 203262.0, 12489.50041998328, 12514.31171285588, 12591.748165036832, 12872.485100596059, 13317.800232996513, 16164.434244801376, 16400.015999040097, 16475.929064610762, 16493.257803398876, 16495.83504164967, 8862.955685221661, 8955.955220223988, 8960.820783584291, 8987.0, 9044.457332559936, 6996.650167491605, 8445.619947102396, 8594.0, 8603.655853765904, 8671.569191083401, 6427.517936154807, 7416.703331866773, 7767.223277672188, 8235.752927412188, 8262.545559994138, 2289.1301305504135, 2305.619572770474, 2307.584634765725, 2311.861288322706, 2485.8508489490687, 785.4698078796789, 937.8686983822271, 996.626265150554, 998.9400635961865, 1005.3364779245662, 15239.619009524851, 20287.289944852153, 21083.68374474384, 21111.366659000254, 21542.892285538474, 966.9854952175582, 986.146982859821, 1006.1196453103535, 1013.8935411781804, 1016.8068067067234, 9234.445933244027, 10946.726331841768, 10970.616028438966, 10992.900709928943, 11210.775784484427, 553025.4697452975, 555326.0, 563173.0, 567862.2137786188, 568012.0, 154079.0, 158734.0, 159268.0, 159653.0, 161240.0, 1133.2294040052843, 1150.4937827355936, 1166.311875993168, 1166.9941650291864, 1171.4377098992532, 17093.23080461407, 18097.185626647097, 18693.81306186921, 18766.061696914825, 18925.526861828563, 65362.673186635664, 66106.0, 66833.66583167053, 68044.0, 68085.63828723496, 89779.55110224408, 90739.54630227071, 90942.09057909336, 91208.54395727979, 91396.54301728714, 8340.666373344999, 8362.749117526484, 9966.551505182138, 10057.446840423847, 10079.546420410952, 13255.337233138112, 16941.23764430579, 17834.375796847566, 17913.37303194382, 18005.279788808824, 14542.4910128145, 14601.0, 14711.55865324042, 14762.0, 14783.482578110144, 12866.35668216604, 13158.868411316147, 13254.933725331697, 13259.867401325862, 13340.933295333462, 13698.698623630738, 14647.19440430744, 14743.705125897208, 14780.408783648534, 14909.329080191634, 9150.679726209552, 9950.651727189514, 10044.648437304659, 10047.899521004696, 10047.899521004696, 13976.231307278198, 14376.353064112349, 14430.783538247144, 14533.0, 14537.709245815236], "value": 10915.626426092345, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1219.213607223341, 1259.533972430201, 1451.8475560066186, 1456.6358410397402, 1460.2625674034618, 11564.017058550033, 11663.0, 12182.939085304572, 12337.259764414119, 12875.034372422071, 10137.239707021967, 11923.7615247695, 12195.451204695788, 12204.816927746091, 12218.32799196044, 1964.7936966618443, 2326.7091613548264, 2355.152145227725, 2361.3624321433203, 2363.019346971005, 2454.47228845799, 2469.7283298837115, 2488.539620170265, 2496.163785131983, 2497.9750202497926, 2461.7045954485397, 2467.8889449974795, 2486.0304481252238, 2488.726240113586, 2491.6013437849947, 2414.5774489464275, 2436.7319594844553, 2443.3525115844313, 2451.6077427611494, 2453.5583594952814, 2238.5858616156065, 2260.1298500077423, 2414.770596793302, 2454.2882564056404, 2540.136353639771, 11673.591424300193, 11761.764764704743, 11771.470283837249, 11776.882231177664, 11792.528298868036, 6366.968165159191, 6630.900536491957, 6632.602043877383, 6633.900491492632, 6635.369639884203, 3770.5098337215986, 3912.667423268997, 3993.7204395692315, 4252.340887162495, 4267.210566045292, 1208.112037652325, 1213.205350495427, 1219.1161407979232, 1220.341015851448, 1242.2174030360904, 15231.695366092727, 15622.140782256858, 16495.092769897652, 16629.0, 16644.83355166457, 4764.714117152982, 5556.91664625031, 5561.304836895388, 5735.541156707537, 5753.88492230153, 12266.141370104007, 12347.876521234673, 12378.195417298028, 12397.62807115788, 12401.565945191875, 6470.061841033118, 6568.211814582281, 6569.0, 6577.980413035987, 6580.736770529125, 6020.367861374492, 6096.634201947897, 6125.356837532084, 6134.938650613526, 6218.626882387071, 3638.3632864248652, 4380.342948557742, 4422.248217802917, 4521.909561808746, 4524.117797029613, 1254.0, 1270.4600544768437, 1277.1800184736712, 1283.0377217087184, 1288.6907142285784, 783.7962129846281, 802.1657476224661, 804.8429514216292, 805.983880322402, 820.429801288099, 6173.9073913891325, 6735.663216839139, 6751.156105486814, 6877.3810357067105, 6965.407940325028, 881.0880738435688, 923.4413180026122, 939.7368736753583, 941.9811603767886, 954.2175416158683, 3978.7214894957074, 4056.492938382702, 4064.0, 4145.730527515762, 4162.895927601834, 14708.0, 15201.239938003055, 15406.768898466533, 15409.0, 15442.691146177014, 14465.421383144556, 15161.241937903062, 15311.61720956985, 15317.0, 15359.846401535842, 7074.0, 8507.234348908629, 8628.007779105357, 8665.0, 8679.739607811776, 6083.726232319645, 6183.628982261079, 6712.83217919556, 6992.699489813626, 7008.229094799466, 11203.327800332007, 13013.479460821462, 13108.541201058299, 13213.471461141831, 13375.464981401024, 10548.419836909045, 13274.469021239429, 13541.458341666617, 13601.523946662215, 13640.976926730733, 9627.229821614394, 9828.213742900407, 9828.459434731158, 9833.60665573369, 9847.60609575609, 10763.138948883914, 10866.565337386415, 10884.945575272073, 10939.12487001022, 11007.779844402907, 8961.551922403723, 9108.362414630912, 9262.212711919563, 9294.72115836526, 9370.344075914893, 7386.519876208029, 9038.0, 9155.725328240163, 9156.404833685789, 9338.486383248986, 8133.389995750224, 8202.810592464179, 8241.423100383156, 8244.464109832841, 8261.586920654063, 5912.70436478183, 5963.463288304073, 6025.758969641165, 6122.142899994094, 6166.321704612399, 5795.652260864362, 5877.441643043904, 5894.440028197486, 6004.81985540417, 6050.394960503914], "value": 5341.620488146804, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.86812483755534, 403.7032780906034, 405.9776712280825, 412.57092623671383, 414.42601996235203, 5213.817516386932, 6127.601705889125, 6310.747570097196, 6342.46089082428, 6374.553781235315, 5297.073012222855, 5636.520895723868, 5782.595218334708, 6139.907901381472, 6177.559863853775, 4557.931631025537, 4562.771861406933, 4567.4062371891605, 4568.862934111982, 4586.280144945646, 3938.7833669148185, 4108.219438306727, 4444.244478438657, 4584.7936842842155, 4586.678932474728, 3971.702122340836, 4116.650084742801, 4493.281075027996, 4554.453465584134, 4559.212826532144, 4128.442660240859, 4521.706089104214, 4540.545945405466, 4551.294549344857, 4564.4522657281, 3934.783586902719, 3941.1329507508303, 3947.6577963492264, 3959.1685745993377, 3969.543502497192, 5510.8071217507195, 6058.878822423527, 6079.148919151322, 6111.87776244477, 6118.265808103011, 5416.51251387377, 5418.620696551204, 5427.972860135714, 5430.0, 5452.59105567084, 6572.535299262777, 6598.637074960875, 6606.174228221473, 6622.1722284714415, 6623.569467984614, 4428.446444194476, 4436.622887054604, 4441.511433742286, 4452.465704115527, 4473.3960915276675, 6722.764703235363, 6843.452523798084, 6862.553933994324, 6876.312368763134, 7001.724439557164, 3986.960130398717, 4462.486814016364, 4494.348319493655, 4496.662750293675, 4523.185826551186, 4597.356370108188, 4599.0342028174455, 4603.401557797465, 4604.38846403756, 4609.7695115244105, 5188.584913206934, 5347.037533243975, 5363.410024897258, 5371.865703357447, 5379.273798037215, 6541.542092053511, 6582.243042050225, 6620.933790662128, 6625.602463852184, 6626.241233941353, 1323.7815760399426, 1555.5100143454777, 1559.6958593074469, 1560.3836484588523, 1566.670999090181, 898.8943599372677, 1018.1244130048152, 1037.4916291017498, 1048.192891473561, 1059.390850261103, 5289.0, 5328.387235467971, 6183.381661833871, 6243.287392036326, 6326.68366581669, 5677.886442271131, 6837.623930683761, 6844.1444819397575, 6862.289395688675, 6870.690818913161, 5680.346760122638, 6240.188775459252, 6243.687815609202, 6259.937400625936, 6275.560710750204, 3537.7712041413743, 3563.1626567756557, 3581.4448760442174, 3587.712982961357, 3588.7129029677562, 7119.0, 7133.964330178316, 7135.179454362711, 7149.356557909813, 7189.317014883578, 6197.318294987548, 7066.434685225169, 7082.752103676346, 7102.891513298559, 7116.181639111465, 5721.456461636138, 6467.51493637979, 6578.375054369827, 7104.964475177694, 7109.004739336394, 6204.5967012144065, 6268.843278918063, 6674.965380366051, 6917.861642767016, 6918.481113916376, 6747.122874026345, 6785.423239024738, 6787.89818152738, 6794.675038367669, 6794.942275975732, 5751.712414379348, 6311.747530098744, 7015.614141222281, 7065.894011589728, 7176.856462870818, 4249.702520823636, 4468.64250859924, 4475.888102797456, 4489.229047600032, 4497.302918047708, 5855.414458554111, 6062.333143354139, 6663.068789414269, 6787.355201255873, 6791.2190098139345, 5699.686517241592, 6325.810225693236, 6607.273199948098, 6620.337966203341, 6625.900611490735, 5834.562407819515, 6493.318201588954, 6616.66916654175, 6622.602643841385, 6627.403533681992, 5375.865603359791, 5944.99777612303, 6283.685815709287, 6463.903041454476, 6465.806025819233, 6569.835754106186, 6576.013597960345, 6589.155036589571, 6600.60396376219, 6620.238672552526, 5465.508104270635, 6263.874722505616, 6405.647689377129, 6443.9355606445215, 6449.6130232186215], "value": 4814.8679856298295, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.9086218791556, 829.796699808547, 844.852150873597, 856.3662889461797, 872.9258013068888, 13923.860761392383, 14401.63995900103, 17119.229634666437, 17461.0, 17584.120793960297, 14056.297185140753, 15084.698306033873, 15224.010439321464, 15252.618684532892, 15304.081755094701, 992.1467537917385, 999.2405771613553, 1016.3596933931613, 1020.1787561013381, 1020.2246292817438, 656.9080328753978, 1008.6469735592557, 1017.7201269650831, 1023.462682091904, 1027.1987849477382, 720.0207717504217, 997.130380536494, 1028.7171027967331, 1047.3611097230682, 1048.2190767877942, 845.9154084591553, 1021.4331046269317, 1038.2317085356858, 1039.1946241662697, 1041.9320196798305, 769.084789100968, 770.96916123355, 771.9614019299013, 773.168843493247, 773.8413625206819, 16624.33502659892, 16731.749023764653, 16794.16029198535, 16795.66408671832, 17273.913630431893, 16886.577835554086, 17073.658526829517, 17226.397076102403, 17641.7353739694, 17649.823501765073, 14567.490137845125, 14846.1834599097, 14853.48012819546, 14853.777193342108, 14874.479393221185, 3445.63820798815, 3469.8785542506143, 3471.704905083071, 3473.270613171237, 3481.1645205150835, 137471.0, 138877.0, 139527.30236348856, 139852.0, 139969.3001534996, 12400.379981001095, 13811.17132972025, 14189.716205675828, 14243.430262789372, 14276.072055316372, 25955.610665840028, 26238.950441982106, 27052.729472705414, 27550.724492754816, 27570.5864412034, 7001.894971575431, 7982.521048737094, 8110.878336824952, 8143.0, 8151.5109093454585, 3933.488646475997, 3998.7200895937003, 4000.7799571023875, 4021.9945013746565, 4025.5571887092397, 3692.7415080944074, 3808.5429748430365, 4354.956450435455, 4454.910901782011, 4874.025194961022, 796.4385108498494, 958.3291695812959, 971.9708408747607, 972.2805124208177, 974.8391515400021, 641.9037144428373, 661.8510835062073, 670.9429698475684, 673.5891106425076, 685.4584877946467, 10469.528871200815, 10668.090009826985, 11803.23278986863, 12407.379631018413, 12486.188397754117, 635.269440143829, 648.8086014625602, 649.7401039584204, 654.7413771560207, 668.204836244867, 5249.947500525022, 5254.4220135785035, 5299.49654782795, 5317.7872885084935, 5440.619156659075, 104523.4773826126, 136913.0, 137598.0, 142395.0, 142694.0, 102348.0, 119659.60680786335, 131221.0, 132249.3387533056, 132351.01473477905, 81491.5925420381, 109884.0, 114319.56922799992, 116730.41634791772, 117479.0, 9000.414973026733, 10845.0, 10895.182861285277, 11024.062954648945, 11342.546298147981, 28326.858365708864, 28907.855460722563, 42066.789666051474, 42432.0, 43600.0, 55972.72013639906, 56255.0, 56794.14808777789, 56907.43092569021, 56988.71505642611, 14604.26978651042, 14708.264586770832, 14888.478903238334, 15119.697606047597, 15120.017198882037, 14160.929195353958, 16189.838101618832, 16473.67052658916, 16555.75166372481, 16659.500214993568, 15250.542483725507, 15366.769498457308, 15419.922900385875, 15557.611059723598, 15677.137757423452, 14799.0, 15206.695866082837, 15403.229838508254, 15506.767398489257, 15534.22328883574, 14879.0, 15029.398824046915, 15223.467178648694, 15225.771613425586, 15310.770338444709, 8448.408611397143, 11007.559697612327, 11092.66721998309, 11107.611233606785, 11222.102231821598, 11617.361045142598, 12010.159288849696, 12067.275963442222, 12153.210041347284, 12235.265884046985], "value": 8356.85129850541, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1515.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 169.0, 168.0, 167.0, 113.0, 114.0, 115.0, 116.0, 113.0, 113.0], "value": 129.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 112.0, 112.0, 150.0, 112.0, 107.0, 110.0, 114.0, 132.0, 108.0], "value": 119.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 195.0, 199.0, 207.0, 207.0, 215.0, 222.0, 200.0, 198.0, 198.0], "value": 204.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 57.0, 57.0, 87.0, 59.0, 61.0, 58.0, 59.0, 76.0, 63.0], "value": 65.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 70.0, 85.0, 73.0, 67.0, 68.0, 87.0, 72.0, 70.0, 96.0], "value": 76.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 89.0, 88.0, 89.0, 92.0, 88.0, 88.0, 89.0, 96.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 97.0, 98.0, 96.0, 97.0, 96.0, 96.0, 96.0], "value": 96.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 95.0, 144.0, 81.0, 141.0, 143.0, 90.0, 89.0, 91.0, 91.0], "value": 108.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 51.0, 55.0, 52.0, 51.0, 52.0, 53.0, 50.0, 54.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 63.0, 57.0, 64.0, 58.0, 55.0, 55.0, 63.0, 72.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 85.0, 87.0, 88.0, 85.0, 88.0, 91.0, 149.0, 86.0], "value": 93.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 142.0, 152.0, 145.0, 138.0, 154.0, 142.0, 151.0, 146.0, 154.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 225.0, 230.0, 187.0, 180.0, 187.0, 186.0, 187.0, 185.0, 186.0], "value": 193.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 71.0, 97.0, 97.0, 95.0, 78.0, 84.0, 68.0, 74.0], "value": 80.1, "unit": "score"}]}]} 10:27:31 INFO - Return code: 0 10:27:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:27:31 INFO - # TBPL SUCCESS # 10:27:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:27:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:27:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:27:31 INFO - 2017-06-01 10:27:31.195 screenresolution[1666:11021] starting screenresolution argv=screenresolution get 10:27:31 INFO - 2017-06-01 10:27:31.198 screenresolution[1666:11021] Display 0: 1600x1200x32@60 10:27:31 INFO - 2017-06-01 10:27:31.204 screenresolution[1667:11024] starting screenresolution argv=screenresolution list 10:27:31 INFO - Available Modes on Display 0 10:27:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:27:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:27:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:27:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:27:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:27:31 INFO - Intel Iris: 10:27:31 INFO - Chipset Model: Intel Iris 10:27:31 INFO - Type: GPU 10:27:31 INFO - Bus: Built-In 10:27:31 INFO - VRAM (Dynamic, Max): 1536 MB 10:27:31 INFO - Vendor: Intel (0x8086) 10:27:31 INFO - Device ID: 0x0a2e 10:27:31 INFO - Revision ID: 0x0009 10:27:31 INFO - Displays: 10:27:31 INFO - Display: 10:27:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:27:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:27:31 INFO - Display Serial Number: 1600x1200 60 10:27:31 INFO - Main Display: Yes 10:27:31 INFO - Mirror: Off 10:27:31 INFO - Online: Yes 10:27:31 INFO - Rotation: Supported 10:27:31 INFO - Return code: 0 10:27:31 INFO - Running post-action listener: _resource_record_post_action 10:27:31 INFO - [mozharness: 2017-06-01 17:27:31.439359Z] Finished run-tests step (success) 10:27:31 INFO - Running post-run listener: _resource_record_post_run 10:27:31 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 40535552; Write bytes: 570514944; Read time: 280; Write time: 1746 10:27:31 INFO - TinderboxPrint: CPU usage
53.3% 10:27:31 INFO - TinderboxPrint: I/O read bytes / time
40,535,552 / 280 10:27:31 INFO - TinderboxPrint: I/O write bytes / time
570,514,944 / 1,746 10:27:31 INFO - TinderboxPrint: CPU idle
2,346.9 (46.7%) 10:27:31 INFO - TinderboxPrint: CPU system
1,014.6 (20.2%) 10:27:31 INFO - TinderboxPrint: CPU user
1,660.2 (33.1%) 10:27:31 INFO - TinderboxPrint: Swap in / out
286,539,776 / 0 10:27:31 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230887936; Write bytes: 230696448; Read time: 16741; Write time: 454 10:27:31 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:27:31 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 38700544; Write bytes: 321353728; Read time: 263; Write time: 1241 10:27:31 INFO - Running post-run listener: _upload_blobber_files 10:27:31 INFO - Blob upload gear active. 10:27:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:27:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:27:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:27:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:27:32 INFO - (blobuploader) - INFO - Open directory for files ... 10:27:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:27:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:27:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:27:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:27:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:27:33 INFO - (blobuploader) - INFO - Done attempting. 10:27:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:27:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:27:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:27:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:27:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:27:34 INFO - (blobuploader) - INFO - Done attempting. 10:27:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:27:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:27:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:27:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:27:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:27:35 INFO - (blobuploader) - INFO - Done attempting. 10:27:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:27:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:27:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:27:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:27:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:27:37 INFO - (blobuploader) - INFO - Done attempting. 10:27:37 INFO - (blobuploader) - INFO - Iteration through files over. 10:27:37 INFO - Return code: 0 10:27:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:27:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:27:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/bfd094aede2a219a2ebade2070d31ca26e499685c298dd6af7143beb55f573eefa5c2c8a248763f15154b28fd232920eafe76626a4b09cd395334cc3223a4238", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/59c85f6f2827980b2785e4f9bfc0efc3e20644394891dc0dc36df0bd053ca06301d02b832ddece7c93bca0c8b1352abe4771cb458305ecfce1936509c626e5a8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/eb11ef035acbd40d4a1da2a1a74944f1b15276611ff858d8f89ea939563dd43c12b9af2e34e729621f3a9eef966c6b4219b6838fb555d41fd63e0306c58e8170", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/654984ff50b64e7aaf0f83272891c0feb181eb12f02237ff0dc98dd8be74d1bb5f1d0aa70250c97cf72b1cf2c39436bf298894debb1d5c6317121b9cf0c66d67"} 10:27:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:27:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:27:37 INFO - Contents: 10:27:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/bfd094aede2a219a2ebade2070d31ca26e499685c298dd6af7143beb55f573eefa5c2c8a248763f15154b28fd232920eafe76626a4b09cd395334cc3223a4238", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/59c85f6f2827980b2785e4f9bfc0efc3e20644394891dc0dc36df0bd053ca06301d02b832ddece7c93bca0c8b1352abe4771cb458305ecfce1936509c626e5a8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/eb11ef035acbd40d4a1da2a1a74944f1b15276611ff858d8f89ea939563dd43c12b9af2e34e729621f3a9eef966c6b4219b6838fb555d41fd63e0306c58e8170", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/654984ff50b64e7aaf0f83272891c0feb181eb12f02237ff0dc98dd8be74d1bb5f1d0aa70250c97cf72b1cf2c39436bf298894debb1d5c6317121b9cf0c66d67"} 10:27:37 INFO - Running post-run listener: copy_logs_to_upload_dir 10:27:37 INFO - Copying logs to upload dir... 10:27:37 INFO - mkdir: /builds/slave/test/build/upload/logs 10:27:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.041201 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-01 10:27:38.053457) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 10:27:38.056971) ========= 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.mOYnclJfzA/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.Eav41kw1aE/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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/Cedar/sha512/bfd094aede2a219a2ebade2070d31ca26e499685c298dd6af7143beb55f573eefa5c2c8a248763f15154b28fd232920eafe76626a4b09cd395334cc3223a4238", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/59c85f6f2827980b2785e4f9bfc0efc3e20644394891dc0dc36df0bd053ca06301d02b832ddece7c93bca0c8b1352abe4771cb458305ecfce1936509c626e5a8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/eb11ef035acbd40d4a1da2a1a74944f1b15276611ff858d8f89ea939563dd43c12b9af2e34e729621f3a9eef966c6b4219b6838fb555d41fd63e0306c58e8170", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/654984ff50b64e7aaf0f83272891c0feb181eb12f02237ff0dc98dd8be74d1bb5f1d0aa70250c97cf72b1cf2c39436bf298894debb1d5c6317121b9cf0c66d67"} build_url:https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011137 build_url: 'https://queue.taskcluster.net/v1/task/EKy1MfutT4WdMkszJr6IZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/bfd094aede2a219a2ebade2070d31ca26e499685c298dd6af7143beb55f573eefa5c2c8a248763f15154b28fd232920eafe76626a4b09cd395334cc3223a4238", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/59c85f6f2827980b2785e4f9bfc0efc3e20644394891dc0dc36df0bd053ca06301d02b832ddece7c93bca0c8b1352abe4771cb458305ecfce1936509c626e5a8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/eb11ef035acbd40d4a1da2a1a74944f1b15276611ff858d8f89ea939563dd43c12b9af2e34e729621f3a9eef966c6b4219b6838fb555d41fd63e0306c58e8170", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/654984ff50b64e7aaf0f83272891c0feb181eb12f02237ff0dc98dd8be74d1bb5f1d0aa70250c97cf72b1cf2c39436bf298894debb1d5c6317121b9cf0c66d67"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 10:27:38.090709) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:27:38.091062) ========= 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.mOYnclJfzA/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.Eav41kw1aE/Listeners TMPDIR=/var/folders/hn/w94h68yd1wv9002y06t8rc5r00000w/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.005247 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:27:38.141568) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 10:27:38.142039) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 10:27:38.142567) ========= ========= Total master_lag: 0.19 =========