builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0244 starttime: 1495573092.74 results: success (0) buildid: 20170523124628 builduid: 7e91085eaf5b4a19ab9fc769321f0281 revision: e161af4fc095a433ca4b37c39f25406438e96aa9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 13:58:12.737185) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 13:58:12.737631) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 13:58:12.737922) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 13:58:12.762846) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 13:58:12.763257) ========= 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.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-23 13:58:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 190M=0s 2017-05-23 13:58:12 (190 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093747 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 13:58:12.879896) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 13:58:12.880220) ========= 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.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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.026470 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 13:58:12.928824) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-23 13:58:12.929188) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e161af4fc095a433ca4b37c39f25406438e96aa9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e161af4fc095a433ca4b37c39f25406438e96aa9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-23 13:58:13,030 truncating revision to first 12 chars 2017-05-23 13:58:13,030 Setting DEBUG logging. 2017-05-23 13:58:13,030 attempt 1/10 2017-05-23 13:58:13,030 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e161af4fc095?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-23 13:58:14,108 unpacking tar archive at: mozilla-beta-e161af4fc095/testing/mozharness/ program finished with exit code 0 elapsedTime=1.359420 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-23 13:58:14.310551) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 13:58:14.310869) ========= script_repo_revision: e161af4fc095a433ca4b37c39f25406438e96aa9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 13:58:14.311263) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 13:58:14.311527) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 13:58:14.329772) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-23 13:58:14.330143) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 13:58:14 INFO - MultiFileLogger online at 20170523 13:58:14 in /builds/slave/test 13:58:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 13:58:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:58:14 INFO - {'append_to_log': False, 13:58:14 INFO - 'base_work_dir': '/builds/slave/test', 13:58:14 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:58:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:58:14 INFO - 'branch': 'Mozilla-Beta', 13:58:14 INFO - 'buildbot_json_path': 'buildprops.json', 13:58:14 INFO - 'config_files': ('talos/mac_config.py',), 13:58:14 INFO - 'default_actions': ('clobber', 13:58:14 INFO - 'read-buildbot-config', 13:58:14 INFO - 'download-and-extract', 13:58:14 INFO - 'populate-webroot', 13:58:14 INFO - 'create-virtualenv', 13:58:14 INFO - 'install', 13:58:14 INFO - 'run-tests'), 13:58:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:58:14 INFO - 'download_minidump_stackwalk': True, 13:58:14 INFO - 'download_symbols': 'ondemand', 13:58:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:58:14 INFO - 'tooltool.py': '/tools/tooltool.py', 13:58:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:58:14 INFO - '/tools/misc-python/virtualenv.py')}, 13:58:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:58:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:58:14 INFO - 'gecko_profile': False, 13:58:14 INFO - 'gecko_profile_interval': 0, 13:58:14 INFO - 'installer_path': 'installer.exe', 13:58:14 INFO - 'log_level': 'info', 13:58:14 INFO - 'log_name': 'talos', 13:58:14 INFO - 'log_to_console': True, 13:58:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:58:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:58:14 INFO - 'opt_config_files': (), 13:58:14 INFO - 'pip_index': False, 13:58:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:58:14 INFO - 'cmd': ('bash', 13:58:14 INFO - '-c', 13:58:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:58:14 INFO - 'enabled': True, 13:58:14 INFO - 'halt_on_failure': False, 13:58:14 INFO - 'name': 'check_screen_resolution'},), 13:58:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:58:14 INFO - 'cmd': ('bash', 13:58:14 INFO - '-c', 13:58:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:58:14 INFO - 'enabled': True, 13:58:14 INFO - 'halt_on_failure': False, 13:58:14 INFO - 'name': 'check_screen_resolution'},), 13:58:14 INFO - 'run_cmd_checks_enabled': True, 13:58:14 INFO - 'suite': 'dromaeojs-e10s', 13:58:14 INFO - 'system_bits': '32', 13:58:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:58:14 INFO - 'title': 't-yosemite-r7-0244', 13:58:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:58:14 INFO - 'use_talos_json': True, 13:58:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:58:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:58:14 INFO - 'work_dir': 'build'} 13:58:14 INFO - [mozharness: 2017-05-23 20:58:14.536854Z] Running clobber step. 13:58:14 INFO - Running pre-action listener: _resource_record_pre_action 13:58:14 INFO - Running main action method: clobber 13:58:14 INFO - rmtree: /builds/slave/test/build 13:58:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:58:17 INFO - Running post-action listener: _resource_record_post_action 13:58:17 INFO - [mozharness: 2017-05-23 20:58:17.044780Z] Finished clobber step (success) 13:58:17 INFO - [mozharness: 2017-05-23 20:58:17.044937Z] Running read-buildbot-config step. 13:58:17 INFO - Running pre-action listener: _resource_record_pre_action 13:58:17 INFO - Running main action method: read_buildbot_config 13:58:17 INFO - Using buildbot properties: 13:58:17 INFO - { 13:58:17 INFO - "project": "", 13:58:17 INFO - "product": "firefox", 13:58:17 INFO - "script_repo_revision": "production", 13:58:17 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 13:58:17 INFO - "repository": "", 13:58:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 13:58:17 INFO - "buildid": "20170523124628", 13:58:17 INFO - "pgo_build": "False", 13:58:17 INFO - "basedir": "/builds/slave/test", 13:58:17 INFO - "buildnumber": 1, 13:58:17 INFO - "slavename": "t-yosemite-r7-0244", 13:58:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 13:58:17 INFO - "platform": "yosemite_r7", 13:58:17 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 13:58:17 INFO - "branch": "mozilla-beta", 13:58:17 INFO - "repo_path": "releases/mozilla-beta", 13:58:17 INFO - "revision": "e161af4fc095a433ca4b37c39f25406438e96aa9", 13:58:17 INFO - "stage_platform": "macosx64", 13:58:17 INFO - "builduid": "7e91085eaf5b4a19ab9fc769321f0281", 13:58:17 INFO - "slavebuilddir": "test" 13:58:17 INFO - } 13:58:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 13:58:17 INFO - Running post-action listener: _resource_record_post_action 13:58:17 INFO - [mozharness: 2017-05-23 20:58:17.048416Z] Finished read-buildbot-config step (success) 13:58:17 INFO - [mozharness: 2017-05-23 20:58:17.048563Z] Running download-and-extract step. 13:58:17 INFO - Running pre-action listener: _resource_record_pre_action 13:58:17 INFO - Running main action method: download_and_extract 13:58:17 INFO - mkdir: /builds/slave/test/build/tests 13:58:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:58:17 INFO - trying https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 13:58:17 INFO - Downloading https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 13:58:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 13:58:18 INFO - Downloaded 1287 bytes. 13:58:18 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 13:58:18 INFO - Using the following test package requirements: 13:58:18 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:58:18 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 13:58:18 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 13:58:18 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:58:18 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 13:58:18 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:58:18 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 13:58:18 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 13:58:18 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:58:18 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 13:58:18 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 13:58:18 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:58:18 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 13:58:18 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:58:18 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 13:58:18 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:58:18 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 13:58:18 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:58:18 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 13:58:18 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 13:58:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 13:58:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 13:58:18 INFO - Fetch https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 13:58:19 INFO - Expected file size: 51396729 13:58:19 INFO - Obtained file size: 51396729 13:58:35 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 13:58:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 13:58:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 13:58:35 INFO - Fetch https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 13:58:37 INFO - Expected file size: 51396729 13:58:37 INFO - Obtained file size: 51396729 13:58:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 13:58:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 13:58:52 INFO - Fetch https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 13:58:54 INFO - Expected file size: 14404449 13:58:54 INFO - Obtained file size: 14404449 13:58:54 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')]} 13:58:54 INFO - trying https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:58:54 INFO - Downloading https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 13:58:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 13:58:56 INFO - Downloaded 59649445 bytes. 13:58:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:58:56 INFO - mkdir: /builds/slave/test/properties 13:58:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:58:56 INFO - Writing to file /builds/slave/test/properties/build_url 13:58:56 INFO - Contents: 13:58:56 INFO - build_url:https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:58:56 INFO - Running post-action listener: _resource_record_post_action 13:58:56 INFO - Running post-action listener: set_extra_try_arguments 13:58:56 INFO - [mozharness: 2017-05-23 20:58:56.813771Z] Finished download-and-extract step (success) 13:58:56 INFO - [mozharness: 2017-05-23 20:58:56.813942Z] Running populate-webroot step. 13:58:56 INFO - Running pre-action listener: _resource_record_pre_action 13:58:56 INFO - Running main action method: populate_webroot 13:58:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:58:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:58:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:58:56 INFO - '--tppagecycles', 13:58:56 INFO - '7'], 13:58:56 INFO - 'tests': ['tsvgm']}}, 13:58:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:58:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:58:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:58:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:58:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:58:56 INFO - 'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:58:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:58:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:58:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:58:56 INFO - 'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['damp', 'tps']}, 13:58:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:58:56 INFO - 'tests': ['damp', 'tps']}, 13:58:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['dromaeo_dom']}, 13:58:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:58:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['basic_compositor_video']}, 13:58:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:58:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['a11yr', 13:58:56 INFO - 'ts_paint', 13:58:56 INFO - 'tpaint', 13:58:56 INFO - 'sessionrestore', 13:58:56 INFO - 'sessionrestore_no_auto_restore', 13:58:56 INFO - 'tabpaint']}, 13:58:56 INFO - 'other-e10s': {'tests': ['a11yr', 13:58:56 INFO - 'ts_paint', 13:58:56 INFO - 'tpaint', 13:58:56 INFO - 'sessionrestore', 13:58:56 INFO - 'sessionrestore_no_auto_restore', 13:58:56 INFO - 'tabpaint']}, 13:58:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:58:56 INFO - 'ts_paint', 13:58:56 INFO - 'tpaint', 13:58:56 INFO - 'sessionrestore', 13:58:56 INFO - 'sessionrestore_no_auto_restore', 13:58:56 INFO - 'tabpaint']}, 13:58:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:58:56 INFO - 'ts_paint', 13:58:56 INFO - 'tpaint', 13:58:56 INFO - 'sessionrestore', 13:58:56 INFO - 'sessionrestore_no_auto_restore', 13:58:56 INFO - 'tabpaint']}, 13:58:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['a11yr', 13:58:56 INFO - 'ts_paint', 13:58:56 INFO - 'tpaint', 13:58:56 INFO - 'sessionrestore', 13:58:56 INFO - 'sessionrestore_no_auto_restore', 13:58:56 INFO - 'tabpaint']}, 13:58:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['a11yr', 13:58:56 INFO - 'ts_paint', 13:58:56 INFO - 'tpaint', 13:58:56 INFO - 'sessionrestore', 13:58:56 INFO - 'sessionrestore_no_auto_restore', 13:58:56 INFO - 'tabpaint']}, 13:58:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['tsvgx', 13:58:56 INFO - 'tsvgr_opacity', 13:58:56 INFO - 'tart', 13:58:56 INFO - 'tscrollx', 13:58:56 INFO - 'cart', 13:58:56 INFO - 'tsvg_static']}, 13:58:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:58:56 INFO - 'tsvgr_opacity', 13:58:56 INFO - 'tart', 13:58:56 INFO - 'tscrollx', 13:58:56 INFO - 'cart', 13:58:56 INFO - 'tsvg_static']}, 13:58:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:58:56 INFO - 'talos_options': ['--disable-e10s'], 13:58:56 INFO - 'tests': ['tp5o']}, 13:58:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:58:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:58:56 INFO - 'talos_options': ['--disable-e10s', 13:58:56 INFO - '--xperf_path', 13:58:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:58:56 INFO - 'tests': ['tp5n']}, 13:58:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:58:56 INFO - 'talos_options': ['--xperf_path', 13:58:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:58:56 INFO - 'tests': ['tp5n']}}, 13:58:56 INFO - 'talos.zip': {'path': '', 13:58:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:58:56 INFO - Running post-action listener: _resource_record_post_action 13:58:56 INFO - [mozharness: 2017-05-23 20:58:56.834756Z] Finished populate-webroot step (success) 13:58:56 INFO - [mozharness: 2017-05-23 20:58:56.834877Z] Running create-virtualenv step. 13:58:56 INFO - Running pre-action listener: _resource_record_pre_action 13:58:56 INFO - Running main action method: create_virtualenv 13:58:56 INFO - Creating virtualenv /builds/slave/test/build/venv 13:58:56 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 13:58:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:58:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:58:56 INFO - Using real prefix '/tools/python27' 13:58:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:58:57 INFO - Installing distribute.............................................................................................................................................................................................done. 13:59:00 INFO - Installing pip.................done. 13:59:00 INFO - Return code: 0 13:59:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:59:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:59:00 INFO - Reading from file tmpfile_stdout 13:59:00 INFO - Output received: 13:59:00 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) 13:59:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ce20b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f9581c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfed30>, '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 0x10cb25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbf3be8>, '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.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 13:59:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:59:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:59:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sEvtZtLGrO/Render', 13:59:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:00 INFO - 'HOME': '/Users/cltbld', 13:59:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:00 INFO - 'LOGNAME': 'cltbld', 13:59:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:00 INFO - 'MOZ_NO_REMOTE': '1', 13:59:00 INFO - 'NO_EM_RESTART': '1', 13:59:00 INFO - 'PAGER': '/bin/cat', 13:59:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:00 INFO - 'PWD': '/builds/slave/test', 13:59:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:00 INFO - 'SHELL': '/bin/bash', 13:59:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbg6LBO8Rb/Listeners', 13:59:00 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 13:59:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:00 INFO - 'USER': 'cltbld', 13:59:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:00 INFO - 'XPC_FLAGS': '0x0', 13:59:00 INFO - 'XPC_SERVICE_NAME': '0', 13:59:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:01 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 13:59:01 INFO - Cleaning up... 13:59:01 INFO - Return code: 0 13:59:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:59:01 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')]} 13:59:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:01 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')]} 13:59:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:01 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 0x10ce20b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f9581c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfed30>, '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 0x10cb25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbf3be8>, '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.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 13:59:01 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 13:59:01 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 13:59:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sEvtZtLGrO/Render', 13:59:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:01 INFO - 'HOME': '/Users/cltbld', 13:59:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:01 INFO - 'LOGNAME': 'cltbld', 13:59:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:01 INFO - 'MOZ_NO_REMOTE': '1', 13:59:01 INFO - 'NO_EM_RESTART': '1', 13:59:01 INFO - 'PAGER': '/bin/cat', 13:59:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:01 INFO - 'PWD': '/builds/slave/test', 13:59:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:01 INFO - 'SHELL': '/bin/bash', 13:59:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbg6LBO8Rb/Listeners', 13:59:01 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 13:59:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:01 INFO - 'USER': 'cltbld', 13:59:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:01 INFO - 'XPC_FLAGS': '0x0', 13:59:01 INFO - 'XPC_SERVICE_NAME': '0', 13:59:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:01 INFO - Downloading/unpacking psutil>=3.1.1 13:59:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:59:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:59:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:59:03 INFO - Installing collected packages: psutil 13:59:03 INFO - Running setup.py install for psutil 13:59:03 INFO - building 'psutil._psutil_osx' extension 13:59:03 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 13:59:03 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 13:59:04 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 13:59:04 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 13:59:04 INFO - building 'psutil._psutil_posix' extension 13:59:04 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 13:59:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:59:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:59:04 INFO - ^ 13:59:04 INFO - 1 warning generated. 13:59:04 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 13:59:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:59:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:59:04 INFO - Successfully installed psutil 13:59:04 INFO - Cleaning up... 13:59:04 INFO - Return code: 0 13:59:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:59:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ce20b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f9581c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfed30>, '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 0x10cb25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbf3be8>, '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.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 13:59:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:59:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:59:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sEvtZtLGrO/Render', 13:59:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:04 INFO - 'HOME': '/Users/cltbld', 13:59:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:04 INFO - 'LOGNAME': 'cltbld', 13:59:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:04 INFO - 'MOZ_NO_REMOTE': '1', 13:59:04 INFO - 'NO_EM_RESTART': '1', 13:59:04 INFO - 'PAGER': '/bin/cat', 13:59:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:04 INFO - 'PWD': '/builds/slave/test', 13:59:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:04 INFO - 'SHELL': '/bin/bash', 13:59:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbg6LBO8Rb/Listeners', 13:59:04 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 13:59:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:04 INFO - 'USER': 'cltbld', 13:59:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:04 INFO - 'XPC_FLAGS': '0x0', 13:59:04 INFO - 'XPC_SERVICE_NAME': '0', 13:59:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:59:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:59:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:59:06 INFO - Installing collected packages: mozsystemmonitor 13:59:06 INFO - Running setup.py install for mozsystemmonitor 13:59:06 INFO - Successfully installed mozsystemmonitor 13:59:06 INFO - Cleaning up... 13:59:06 INFO - Return code: 0 13:59:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:59:06 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')]} 13:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:06 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')]} 13:59:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:06 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 0x10ce20b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f9581c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfed30>, '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 0x10cb25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbf3be8>, '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.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 13:59:06 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 13:59:06 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 13:59:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sEvtZtLGrO/Render', 13:59:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:06 INFO - 'HOME': '/Users/cltbld', 13:59:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:06 INFO - 'LOGNAME': 'cltbld', 13:59:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:06 INFO - 'MOZ_NO_REMOTE': '1', 13:59:06 INFO - 'NO_EM_RESTART': '1', 13:59:06 INFO - 'PAGER': '/bin/cat', 13:59:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:06 INFO - 'PWD': '/builds/slave/test', 13:59:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:06 INFO - 'SHELL': '/bin/bash', 13:59:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbg6LBO8Rb/Listeners', 13:59:06 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 13:59:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:06 INFO - 'USER': 'cltbld', 13:59:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:06 INFO - 'XPC_FLAGS': '0x0', 13:59:06 INFO - 'XPC_SERVICE_NAME': '0', 13:59:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:06 INFO - Downloading/unpacking jsonschema==2.5.1 13:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:59:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:59:08 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 13:59:08 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 13:59:08 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 13:59:08 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 13:59:08 INFO - Downloading functools32-3.2.3-2.tar.gz 13:59:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:59:08 INFO - warning: no files found matching '*.txt' 13:59:08 INFO - no previously-included directories found matching 'build' 13:59:08 INFO - no previously-included directories found matching 'dist' 13:59:08 INFO - no previously-included directories found matching '.git*' 13:59:08 INFO - Installing collected packages: jsonschema, functools32 13:59:08 INFO - Running setup.py install for functools32 13:59:08 INFO - warning: no files found matching '*.txt' 13:59:08 INFO - no previously-included directories found matching 'build' 13:59:08 INFO - no previously-included directories found matching 'dist' 13:59:08 INFO - no previously-included directories found matching '.git*' 13:59:08 INFO - Successfully installed jsonschema functools32 13:59:08 INFO - Cleaning up... 13:59:08 INFO - Return code: 0 13:59:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:08 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 0x10ce20b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f9581c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfed30>, '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 0x10cb25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbf3be8>, '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.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 13:59:08 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 13:59:08 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 13:59:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sEvtZtLGrO/Render', 13:59:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:08 INFO - 'HOME': '/Users/cltbld', 13:59:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:08 INFO - 'LOGNAME': 'cltbld', 13:59:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:08 INFO - 'MOZ_NO_REMOTE': '1', 13:59:08 INFO - 'NO_EM_RESTART': '1', 13:59:08 INFO - 'PAGER': '/bin/cat', 13:59:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:08 INFO - 'PWD': '/builds/slave/test', 13:59:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:08 INFO - 'SHELL': '/bin/bash', 13:59:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbg6LBO8Rb/Listeners', 13:59:08 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 13:59:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:08 INFO - 'USER': 'cltbld', 13:59:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:08 INFO - 'XPC_FLAGS': '0x0', 13:59:08 INFO - 'XPC_SERVICE_NAME': '0', 13:59:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:59:08 INFO - Cleaning up... 13:59:08 INFO - Return code: 0 13:59:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:08 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 0x10ce20b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f9581c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfed30>, '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 0x10cb25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbf3be8>, '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.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 13:59:08 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 13:59:08 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 13:59:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sEvtZtLGrO/Render', 13:59:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:08 INFO - 'HOME': '/Users/cltbld', 13:59:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:08 INFO - 'LOGNAME': 'cltbld', 13:59:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:08 INFO - 'MOZ_NO_REMOTE': '1', 13:59:08 INFO - 'NO_EM_RESTART': '1', 13:59:08 INFO - 'PAGER': '/bin/cat', 13:59:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:08 INFO - 'PWD': '/builds/slave/test', 13:59:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:08 INFO - 'SHELL': '/bin/bash', 13:59:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbg6LBO8Rb/Listeners', 13:59:08 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 13:59:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:08 INFO - 'USER': 'cltbld', 13:59:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:08 INFO - 'XPC_FLAGS': '0x0', 13:59:08 INFO - 'XPC_SERVICE_NAME': '0', 13:59:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:09 INFO - Downloading/unpacking blobuploader==1.2.4 13:59:09 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 13:59:09 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 13:59:09 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 13:59:09 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 13:59:10 INFO - Downloading blobuploader-1.2.4.tar.gz 13:59:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:59:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:59:10 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 13:59:10 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 13:59:10 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 13:59:10 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 13:59:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:59:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:11 INFO - Downloading docopt-0.6.1.tar.gz 13:59:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:59:11 INFO - Installing collected packages: blobuploader, requests, docopt 13:59:11 INFO - Running setup.py install for blobuploader 13:59:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:59:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:59:11 INFO - Running setup.py install for requests 13:59:12 INFO - Running setup.py install for docopt 13:59:12 INFO - Successfully installed blobuploader requests docopt 13:59:12 INFO - Cleaning up... 13:59:12 INFO - Return code: 0 13:59:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:59:12 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')]} 13:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:13 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 0x10ce20b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f9581c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfed30>, '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 0x10cb25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbf3be8>, '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.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 13:59:13 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 13:59:13 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 13:59:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sEvtZtLGrO/Render', 13:59:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:13 INFO - 'HOME': '/Users/cltbld', 13:59:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:13 INFO - 'LOGNAME': 'cltbld', 13:59:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:13 INFO - 'MOZ_NO_REMOTE': '1', 13:59:13 INFO - 'NO_EM_RESTART': '1', 13:59:13 INFO - 'PAGER': '/bin/cat', 13:59:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:13 INFO - 'PWD': '/builds/slave/test', 13:59:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:13 INFO - 'SHELL': '/bin/bash', 13:59:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbg6LBO8Rb/Listeners', 13:59:13 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 13:59:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:13 INFO - 'USER': 'cltbld', 13:59:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:13 INFO - 'XPC_FLAGS': '0x0', 13:59:13 INFO - 'XPC_SERVICE_NAME': '0', 13:59:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:59:13 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-Ox4J0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:59:13 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-fIJ9YR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:59:13 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-bykaX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:59:13 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-UDBa3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:59:13 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-HYkkEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:59:13 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-iEze97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:59:13 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-2muFuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:59:14 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-NLjF9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:59:14 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-WEHyv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:59:14 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-3XNF60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:59:14 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-URyMJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:59:14 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-YCOXHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:59:14 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-1RyZ8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:59:14 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-FMlUyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:14 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-shF7hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:59:14 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-s4I4HX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:59:14 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-6cQXVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:59:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:59:15 INFO - Running setup.py install for manifestparser 13:59:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:59:15 INFO - Running setup.py install for mozcrash 13:59:15 INFO - Running setup.py install for mozdebug 13:59:15 INFO - Running setup.py install for mozdevice 13:59:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:59:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:59:15 INFO - Running setup.py install for mozfile 13:59:15 INFO - Running setup.py install for mozhttpd 13:59:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:59:15 INFO - Running setup.py install for mozinfo 13:59:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:59:16 INFO - Running setup.py install for mozInstall 13:59:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:59:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:59:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:59:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:59:16 INFO - Running setup.py install for mozleak 13:59:16 INFO - Running setup.py install for mozlog 13:59:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:59:16 INFO - Running setup.py install for moznetwork 13:59:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:59:16 INFO - Running setup.py install for mozprocess 13:59:16 INFO - Running setup.py install for mozprofile 13:59:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:59:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:59:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:59:17 INFO - Running setup.py install for mozrunner 13:59:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:59:17 INFO - Running setup.py install for mozscreenshot 13:59:17 INFO - Running setup.py install for moztest 13:59:17 INFO - Running setup.py install for mozversion 13:59:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:59:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:59:17 INFO - Cleaning up... 13:59:17 INFO - Return code: 0 13:59:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:17 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 0x10ce20b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f9581c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfed30>, '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 0x10cb25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbf3be8>, '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.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 13:59:17 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 13:59:17 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 13:59:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sEvtZtLGrO/Render', 13:59:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:17 INFO - 'HOME': '/Users/cltbld', 13:59:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:17 INFO - 'LOGNAME': 'cltbld', 13:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:17 INFO - 'MOZ_NO_REMOTE': '1', 13:59:17 INFO - 'NO_EM_RESTART': '1', 13:59:17 INFO - 'PAGER': '/bin/cat', 13:59:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:17 INFO - 'PWD': '/builds/slave/test', 13:59:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:17 INFO - 'SHELL': '/bin/bash', 13:59:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbg6LBO8Rb/Listeners', 13:59:17 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 13:59:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:17 INFO - 'USER': 'cltbld', 13:59:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:17 INFO - 'XPC_FLAGS': '0x0', 13:59:17 INFO - 'XPC_SERVICE_NAME': '0', 13:59:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:59:17 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-PKQyOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:59:18 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)) 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:59:18 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-zipL9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:59:18 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)) 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:59:18 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-O81hJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:59:18 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)) 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:59:18 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-BNbFDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:59:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:59:18 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-0nF7XT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:59:18 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)) 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:59:18 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-8JWxNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:59:18 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)) 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:59:18 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-vS76mN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:59:18 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)) 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:59:18 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-0ZDM2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:59:18 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)) 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:59:18 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-BmA9C4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:59:18 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)) 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:59:18 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-l4nq40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:59:18 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)) 13:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:59:18 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-x9NBeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:59:19 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)) 13:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:59:19 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-wc4m9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:59:19 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)) 13:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:59:19 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-5wt4sJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:59:19 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)) 13:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:59:19 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-wLsSQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:59:19 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)) 13:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:19 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-c0Fgyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:19 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)) 13:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:59:19 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-iwZSpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:59:19 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)) 13:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:59:19 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-VIe8z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:59:19 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)) 13:59:19 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)) 13:59:19 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)) 13:59:19 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)) 13:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:59:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:59: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 13:59: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 13:59: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 13:59: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 13:59:21 INFO - Downloading blessings-1.6.tar.gz 13:59:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:59:21 INFO - Installing collected packages: blessings 13:59:21 INFO - Running setup.py install for blessings 13:59:21 INFO - Successfully installed blessings 13:59:21 INFO - Cleaning up... 13:59:21 INFO - Return code: 0 13:59:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:59:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:59:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:59:21 INFO - Reading from file tmpfile_stdout 13:59:21 INFO - Current package versions: 13:59:21 INFO - blessings == 1.6 13:59:21 INFO - blobuploader == 1.2.4 13:59:21 INFO - docopt == 0.6.1 13:59:21 INFO - functools32 == 3.2.3-2 13:59:21 INFO - jsonschema == 2.5.1 13:59:21 INFO - manifestparser == 1.1 13:59:21 INFO - mozInstall == 1.12 13:59:21 INFO - mozcrash == 1.0 13:59:21 INFO - mozdebug == 0.1 13:59:21 INFO - mozdevice == 0.49 13:59:21 INFO - mozfile == 1.2 13:59:21 INFO - mozhttpd == 0.7 13:59:21 INFO - mozinfo == 0.9 13:59:21 INFO - mozleak == 0.1 13:59:21 INFO - mozlog == 3.4 13:59:21 INFO - moznetwork == 0.27 13:59:21 INFO - mozprocess == 0.25 13:59:21 INFO - mozprofile == 0.28 13:59:21 INFO - mozrunner == 6.13 13:59:21 INFO - mozscreenshot == 0.1 13:59:21 INFO - mozsystemmonitor == 0.3 13:59:21 INFO - moztest == 0.8 13:59:21 INFO - mozversion == 1.4 13:59:21 INFO - psutil == 3.1.1 13:59:21 INFO - requests == 1.2.3 13:59:21 INFO - wsgiref == 0.1.2 13:59:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:59:21 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')]} 13:59:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:21 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')]} 13:59:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:21 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 0x10ce20b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f9581c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfed30>, '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 0x10cb25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbf3be8>, '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.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 13:59:21 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 13:59:21 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 13:59:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sEvtZtLGrO/Render', 13:59:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:21 INFO - 'HOME': '/Users/cltbld', 13:59:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:21 INFO - 'LOGNAME': 'cltbld', 13:59:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:21 INFO - 'MOZ_NO_REMOTE': '1', 13:59:21 INFO - 'NO_EM_RESTART': '1', 13:59:21 INFO - 'PAGER': '/bin/cat', 13:59:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:21 INFO - 'PWD': '/builds/slave/test', 13:59:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:21 INFO - 'SHELL': '/bin/bash', 13:59:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbg6LBO8Rb/Listeners', 13:59:21 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 13:59:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:21 INFO - 'USER': 'cltbld', 13:59:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:21 INFO - 'XPC_FLAGS': '0x0', 13:59:21 INFO - 'XPC_SERVICE_NAME': '0', 13:59:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:22 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)) 13:59:22 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)) 13:59:22 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)) 13:59:22 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)) 13:59:22 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)) 13:59:22 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)) 13:59:22 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)) 13:59:22 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)) 13:59:22 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)) 13:59:22 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)) 13:59:22 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)) 13:59:22 INFO - Cleaning up... 13:59:22 INFO - Return code: 0 13:59:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:22 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 0x10ce20b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f9581c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfed30>, '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 0x10cb25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbf3be8>, '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.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 13:59:22 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 13:59:22 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 13:59:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sEvtZtLGrO/Render', 13:59:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:22 INFO - 'HOME': '/Users/cltbld', 13:59:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:22 INFO - 'LOGNAME': 'cltbld', 13:59:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:22 INFO - 'MOZ_NO_REMOTE': '1', 13:59:22 INFO - 'NO_EM_RESTART': '1', 13:59:22 INFO - 'PAGER': '/bin/cat', 13:59:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:22 INFO - 'PWD': '/builds/slave/test', 13:59:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:22 INFO - 'SHELL': '/bin/bash', 13:59:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbg6LBO8Rb/Listeners', 13:59:22 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 13:59:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:22 INFO - 'USER': 'cltbld', 13:59:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:22 INFO - 'XPC_FLAGS': '0x0', 13:59:22 INFO - 'XPC_SERVICE_NAME': '0', 13:59:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:59:22 INFO - Cleaning up... 13:59:22 INFO - Return code: 0 13:59:22 INFO - Running post-action listener: _resource_record_post_action 13:59:22 INFO - Running post-action listener: _start_resource_monitoring 13:59:22 INFO - Starting resource monitoring. 13:59:22 INFO - [mozharness: 2017-05-23 20:59:22.324065Z] Finished create-virtualenv step (success) 13:59:22 INFO - [mozharness: 2017-05-23 20:59:22.325031Z] Running install step. 13:59:22 INFO - Running pre-action listener: _resource_record_pre_action 13:59:22 INFO - Running main action method: install 13:59:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:59:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:59:22 INFO - Reading from file tmpfile_stdout 13:59:22 INFO - Detecting whether we're running mozinstall >=1.0... 13:59:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:59:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:59:22 INFO - Reading from file tmpfile_stdout 13:59:22 INFO - Output received: 13:59:22 INFO - Usage: mozinstall [options] installer 13:59:22 INFO - Options: 13:59:22 INFO - -h, --help show this help message and exit 13:59:22 INFO - -d DEST, --destination=DEST 13:59:22 INFO - Directory to install application into. [default: 13:59:22 INFO - "/builds/slave/test"] 13:59:22 INFO - --app=APP Application being installed. [default: firefox] 13:59:22 INFO - mkdir: /builds/slave/test/build/application 13:59:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:59:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 13:59:37 INFO - Reading from file tmpfile_stdout 13:59:37 INFO - Output received: 13:59:37 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 13:59:37 INFO - Running post-action listener: _resource_record_post_action 13:59:37 INFO - [mozharness: 2017-05-23 20:59:37.552814Z] Finished install step (success) 13:59:37 INFO - [mozharness: 2017-05-23 20:59:37.552957Z] Running run-tests step. 13:59:37 INFO - Running pre-action listener: _resource_record_pre_action 13:59:37 INFO - Running main action method: run_tests 13:59:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:59:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:59:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:59:37 INFO - 2017-05-23 13:59:37.567 screenresolution[1624:7340] starting screenresolution argv=screenresolution get 13:59:37 INFO - 2017-05-23 13:59:37.570 screenresolution[1624:7340] Display 0: 1600x1200x32@60 13:59:37 INFO - 2017-05-23 13:59:37.576 screenresolution[1625:7342] starting screenresolution argv=screenresolution list 13:59:37 INFO - Available Modes on Display 0 13:59:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:59:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:59:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:59:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:59:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:59:37 INFO - Intel Iris: 13:59:37 INFO - Chipset Model: Intel Iris 13:59:37 INFO - Type: GPU 13:59:37 INFO - Bus: Built-In 13:59:37 INFO - VRAM (Dynamic, Max): 1536 MB 13:59:37 INFO - Vendor: Intel (0x8086) 13:59:37 INFO - Device ID: 0x0a2e 13:59:37 INFO - Revision ID: 0x0009 13:59:37 INFO - Displays: 13:59:37 INFO - Display: 13:59:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:59:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:59:37 INFO - Display Serial Number: 1600x1200 60 13:59:37 INFO - Main Display: Yes 13:59:37 INFO - Mirror: Off 13:59:37 INFO - Online: Yes 13:59:37 INFO - Rotation: Supported 13:59:37 INFO - Return code: 0 13:59:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:59:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:59:37 INFO - Python 2.7.3 13:59:37 INFO - Return code: 0 13:59:37 INFO - grabbing minidump binary from tooltool 13:59:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:37 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 0x10cbfed30>, '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 0x10cb25db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbf3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:59:37 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 13:59:37 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 13:59:37 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 13:59:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:59:37 INFO - Return code: 0 13:59:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:59:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:59:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:59:37 INFO - ENV: RUST_BACKTRACE is now 1 13:59:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:59:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:59:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0244', '--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 13:59:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0244 --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 13:59:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sEvtZtLGrO/Render', 13:59:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:37 INFO - 'HOME': '/Users/cltbld', 13:59:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:37 INFO - 'LOGNAME': 'cltbld', 13:59:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:59:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:59:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:37 INFO - 'MOZ_NO_REMOTE': '1', 13:59:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:59:37 INFO - 'NO_EM_RESTART': '1', 13:59:37 INFO - 'PAGER': '/bin/cat', 13:59:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:37 INFO - 'PWD': '/builds/slave/test', 13:59:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:59:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:37 INFO - 'RUST_BACKTRACE': '1', 13:59:37 INFO - 'SHELL': '/bin/bash', 13:59:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbg6LBO8Rb/Listeners', 13:59:37 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 13:59:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:37 INFO - 'USER': 'cltbld', 13:59:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:37 INFO - 'XPC_FLAGS': '0x0', 13:59:37 INFO - 'XPC_SERVICE_NAME': '0', 13:59:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0244', '--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 13:59:38 INFO - mozversion application_buildid: 20170523124628 13:59:38 INFO - mozversion application_changeset: e161af4fc095a433ca4b37c39f25406438e96aa9 13:59:38 INFO - mozversion application_display_name: Firefox 13:59:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:59:38 INFO - mozversion application_name: Firefox 13:59:38 INFO - mozversion application_remotingname: firefox 13:59:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:59:38 INFO - mozversion application_vendor: Mozilla 13:59:38 INFO - mozversion application_version: 54.0 13:59:38 INFO - mozversion platform_buildid: 20170523124628 13:59:38 INFO - mozversion platform_changeset: e161af4fc095a433ca4b37c39f25406438e96aa9 13:59:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:59:38 INFO - mozversion platform_version: 54.0 13:59:38 INFO - using testdate: 1495573178 13:59:38 INFO - actual date: 1495573178 13:59:38 INFO - starting webserver on 'localhost:49218' 13:59:38 INFO - SUITE-START | Running 2 tests 13:59:38 INFO - TEST-START | dromaeo_css 13:59:38 INFO - operating with platform_type : mac_ 13:59:38 INFO - Initialising browser for dromaeo_css test... 13:59:38 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpUZDTof/profile http://localhost:49218/getInfo.html) 13:59:40 INFO - PROCESS | 1635 | 2017-05-23 13:59:40.903 plugin-container[1637:7501] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 13:59:40 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:59:40 INFO - PROCESS | 1635 | 2017-05-23 13:59:40.904 plugin-container[1637:7501] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x420f, name = 'com.apple.CFPasteboardClient' 13:59:40 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:59:40 INFO - PROCESS | 1635 | 2017-05-23 13:59:40.904 plugin-container[1637:7501] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:59:40 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 13:59:40 INFO - PROCESS | 1635 | colorDepth:24 13:59:40 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 13:59:40 INFO - PROCESS | 1635 | __metrics 13:59:41 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 13:59:41 INFO - TEST-INFO | 1635: exit 0 13:59:41 INFO - Browser initialized. 13:59:41 INFO - Running cycle 1/1 for dromaeo_css test... 13:59:41 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpUZDTof/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:59:43 INFO - PROCESS | 1638 | 2017-05-23 13:59:43.859 plugin-container[1639:7637] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 13:59:43 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:59:43 INFO - PROCESS | 1638 | 2017-05-23 13:59:43.860 plugin-container[1639:7637] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x850f, name = 'com.apple.CFPasteboardClient' 13:59:43 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:59:43 INFO - PROCESS | 1638 | 2017-05-23 13:59:43.860 plugin-container[1639:7637] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:59:53 INFO - PROCESS | 1638 | RSS: Main: 174252032 13:59:53 INFO - PROCESS | 1638 | 14:02:56 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49218/tests/dromaeo/cssquery-ext.html) 14:02:58 INFO - PROCESS | 1638 | RSS: Main: 189370368 14:02:58 INFO - PROCESS | 1638 | 14:06:00 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-ext.html (next: http://localhost:49218/tests/dromaeo/cssquery-jquery.html) 14:06:01 INFO - PROCESS | 1638 | RSS: Main: 202604544 14:06:01 INFO - PROCESS | 1638 | 14:09:03 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49218/tests/dromaeo/cssquery-mootools.html) 14:09:03 INFO - PROCESS | 1638 | RSS: Main: 204595200 14:09:03 INFO - PROCESS | 1638 | 14:12:05 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49218/tests/dromaeo/cssquery-prototype.html) 14:12:06 INFO - PROCESS | 1638 | RSS: Main: 208240640 14:12:06 INFO - PROCESS | 1638 | 14:15:09 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49218/tests/dromaeo/cssquery-yui.html) 14:15:09 INFO - PROCESS | 1638 | RSS: Main: 210927616 14:15:09 INFO - PROCESS | 1638 | 14:18:12 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-yui.html (next: http://localhost:49218/tests/dromaeo/cssquery-dojo.html) 14:18:12 INFO - PROCESS | 1638 | RSS: Main: 209244160 14:18:12 INFO - PROCESS | 1638 | 14:18:12 INFO - PROCESS | 1638 | __start_tp_report 14:18:12 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 14:18:12 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 14:18:12 INFO - PROCESS | 1638 | |i|pagename|runs| 14:18:12 INFO - PROCESS | 1638 | |0;dojo.html;3650.9634903650963;3654.068212605785;3661.505696730942;3668.192997540542;3687.612800655932;5931.347551769302;6195.783147589836;6247.031710084939;6269.247690277173;6274.811755647333;6194.907076393858;6224.875502489947;6228.096925945735;6280.434760871521;6291.40231677991;4665.88335291617;5682.346530149024;5702.372738998707;5709.486146246837;5761.683107429089;5731.828045158652;5735.4551317624755;5746.367899531048;5754.568407369443;5771.047777116771;5543.417941116185;5549.056660367747;5741.425857414246;5749.798757043504;5814.651120932757;5665.3768085510565;5676.659400435987;5736.110902810071;5740.425957404268;5750.511206547449;5520.227168196456;5622.156676498517;5648.717564121818;5654.095344744821;5696.3449203341315;5988.700564971778;6024.397560243984;6206.472449841769;6215.502759779252;6281.089242059921;6417.518686098515;6419.133416988693;6497.447716944065;6501.707423165969;6579.34206579343;6756.729730810762;6757.155355580552;6761.222459417181;6779.186497620317;6783.084283621697;6301.905471417932;6308.300788537544;6355.713992870378;6365.554411191219;6370.777022804226;6549.705263263165;6605.108310378134;6611.206655201407;6749.730010799513;6857.931420685729;6503.804885853432;6508.772192973223;6537.967310163419;6556.344365563501;6557.442617377576;6633.938569828804;6664.666766661648;6697.129373181553;6697.430718388894;6723.361280678328;6341.524385671093;6355.205599300088;6550.967245163743;6567.737290508326;6592.143021407186;5492.862678433071;6337;6505.154329937172;6519.511036672268;6539.313372095957;4571.451425828922;5259.790248242925;5278.261043453919;5289.973550132302;5305.549028332635;4709.764511774398;4718.292256161603;4727.739974301447;4739.170645137087;4749.2876068589985;6134.314131579348;6160.815175544741;6212.751489940442;6295.937040629627;6312.936870631327;5756.971215143897;6346.984482482781;6650.135482387259;6652.73389064432;6694.866102678016;6266.49868010558;6269.341719119518;6304.936950630527;6315.747370105236;6326.905096423466;5649.0114230009585;5735.627184233012;5872.412758724179;5877.4122587740885;5894.410558944071;6710.597364158166;6773.458123350219;6801.18385793708;6805.625690587065;6813.965930170318;6586.077949087035;6679.265280819009;6695.029220763059;6713.966430167818;6725.125733654593;6865.0732151160955;6865.862682746417;6878.58728476293;6878.931210687829;6895.138107736533;6344.873102537831;6402.935970640234;6491.610503369813;6530.6081635100145;6549.279579246184;6660.2673705893285;6661.633610151488;6671.366220209073;6672.266050734318;6692.665366731741;5755.3381361144;6423.871522569429;6486.383793539606;6503.772367967098;6520.250171230369;6270.843228919313;6337.112804207507;6404.295527492065;6407.775727849503;6444.226692796707;6263.874722505616;6340.556161068681;6357.364263573791;6359.618422894641;6400.23197216337;6262.4990000800735;6504.869902602016;6515.218173819172;6519.445847103049;6543.934560654333;6165.84585385351;6207.503399727921;6249.843753906007;6287.339829318039;6312.147860038836 14:18:12 INFO - PROCESS | 1638 | |1;ext.html;784.8587254294229;835.6448509383513;853.9573021348933;894.4946105450424;992.895745946676;39791.403128953054;40147;41209.58790412095;41341.793291033544;41478.963025924364;40096.39855402171;40290.99272518189;40369.39445908314;40407;40420.98947526306;8276.627551760184;11156.497957591888;11409.144314176428;11630.360330181837;11649.650510484698;11292.774144517105;11376.772464550704;11377.2035957483;11395.54417823286;11410.429478526083;11366.60216892409;11389.487473063731;11402.315861048322;11404;11426.771464570704;11276.605318813843;11341.886581134208;11352.48913798881;11363.602273920415;11364.545418183263;9328;9426.481543515103;10961.342319460859;11044.779104417947;11079.556817727282;35627.643723562695;35694.92915212548;35783.64216357829;35787.46318805194;35904.64095359065;4006.639402453793;4026.1545075534173;4049.514058312992;4068.7965601719798;4088;26997.325066873287;28026.299342516395;28202.576961345596;28281.434371312665;28310.86756529736;3111.937761244785;3307.1236122427576;3339.833008349597;3410.0963244740165;3411.096059544223;338482;340448.29775850964;348975;349023;372859.99999999726;45035.774821125684;45192.322115168296;45314.32028519575;45349.31976020362;45405.091898162515;46507.30239046417;46884.7655761719;47003.29495057577;47164.528354716014;47212.5278747215;14116.294185290695;14121.576352709435;14230.43078276878;14301.28493575317;14304.784093352182;12376.133670643148;12499.312537810507;12620.927221186119;12623.926966207791;12640.367981600883;12155.878441215475;12163.756724865452;12173.330466824409;12218.083643726755;12295.200811947374;5752.712364381848;5793.102069179284;5804.1293805929445;5821.854453638693;5835.9708201458725;1021.6219998600566;1063.6011495689118;1081.9342947197006;1160.6053941659877;1168.4741866160261;22694.659580106312;23287.301380958597;25300.240992770247;26946.702394674747;27421;1062.4422178356424;1252.3988485526997;1276.508544210467;1282.570338936448;1311.4623004568082;11570.479328430241;13989.740923316951;14027.08823926462;14219.786703199461;14252.857471425154;415167;418510;420057;420418;420964;56360.43639563634;56378;56724.432755672744;56756.71621641865;56798.7160064197;1476.6234610174552;1491.8731907787742;1519.6124988128179;1527.900686455377;1531.8851086168581;25327.873360633817;26568.734312656627;26960;26960.595591066543;26981;45148.32277515905;45624.771876141735;45625;45712.31431528463;45949.770251149865;399261;402284.98857505526;403008;403459;403674;27715.58426623562;28173.859130705034;31691.366172676877;31842.68157318397;32628;45244.32133517934;45992.31011534896;46178.307325389476;46844.865251277035;47363.28955065746;31603;31755;31785.523217152222;32045.67954320427;32404.35191296208;25827.483450330514;30859.228519287197;31371.90198343047;31743.52384714185;31809.5228571567;37795.86612401632;37889.81055094707;43469.347959779996;43668.34497482604;45641.63075107752;39651.60348396479;42230.7888460568;42270.57729422666;43198.568014320714;43415;32192.678073219904;33500;33634.66365336315;33687.32625347529;33755.3248935015 14:18:12 INFO - PROCESS | 1638 | |2;jquery.html;422.25050535299846;432.250046169895;444.291355288315;444.882106241846;455.8472911574622;21095.894520527396;21521.24675636353;22250.332490025325;22310.996005179764;22407;17340.56648583786;22088.895555222254;22487.55024899501;22666.433339166528;22861.885690571566;6310.927142385805;6677.465802735769;6703.564268322568;6965.5124141310125;6978.581285122884;6779.661016949158;6872.175338959307;6934.72261109555;6946.374826265635;6947.548409353376;5888.440598143169;6624.370684784938;6920.584764914096;6931.410830079424;6943.583384996891;6448.258450278231;6735.22544907337;6791.252962174157;6799.52403331767;6918.688659010331;6560.7703730369685;6561.015847622847;6568.901466478007;6575.441087507567;6597.80206593803;20913.8954305229;26022;26298.737012630012;26434.6034809478;26664.86667566669;3053.267215868198;3054.9541756873664;3056.2206637307568;3072.308730535634;3095.349976504937;15988.800839937052;16763.580910477212;16821.906576072517;16940.745888811678;16990.320387184496;2110.7361579802523;2127.744670639533;2129.169623846701;2129.2973318804757;2130.0627723801476;137581;137909.31045344708;156753;159477.4052259474;187988.06005969882;20164.19343226284;20265.696014559795;20282.290119845733;20409.97950102489;20595.691064634044;29859.70140298569;30072;30387.088387347972;30474.39051218963;30486.54270185949;10236.436995965294;10250.436226007492;10291.639792607222;10417.06246437809;10625.256232063835;8802.427842190236;8802.647894084294;8806.779830504289;8938.46369217849;9022.819543609092;9110.681126160685;9163.31275154366;9184.540772961325;9305.441673499476;9323.72028839136;4222.662187025031;4287.499375218711;4303.160883627663;4320.364577607656;4339.7396156230725;1037.6316407675317;1063.1069901282806;1075.0593230922943;1099.1316859680921;1109.7614012987085;21509.13963441445;21775.67336489954;21781.891090544763;21868.78131218699;21949.451263718216;1093.7593729379523;1115.0967716149783;1123.5618108937522;1133.4672703829187;1139.1228753859482;9753.853692194481;11010.229283950206;11249.381284029294;11281.774364512663;11330.603428880115;683546;754443;755136;765502;784077;166635;169100;169242.92271231883;172033;173768.26231737522;1210.6489118155812;1239.8884100431005;1253.5988483685317;1256.5162412471263;1257.836481257449;18579.32786049262;18655.90672046631;19119.23523059062;19126.521836954187;19143.712844307625;62280.44298892564;69438.9584156228;69544.6522767383;71642.64178679073;71755.92366114409;85232;97302.0269797293;97717;98703.50648246713;99794;11368.772624547628;11708.88291117078;11790.587329443428;11850.229735067192;12045.217060891015;12771.425285862344;12950.740985180055;12956.870431295567;13261.801072984106;13342.332883355599;9364.625414983597;10337.793244134924;11444.94277528607;11495.75832712613;11564.190506664789;9851.261155413229;10350.792984140426;10414.843777343498;10569.101626361538;10916.672499825017;9926.851097233402;11607.36159511235;11778.293302401884;11805.114616403633;11816.881831181921;11550.884491155317;11648.41757912118;11717.765644686888;11737.530498780296;11989.940050299692;9571.37786043905;11287.20989530725;11295;11549.769004619693;11557 14:18:12 INFO - PROCESS | 1638 | |3;mootools.html;1448.898577099603;1589.3245370717443;1657.1962598139908;1660.3690597572927;1660.4022551881317;9775.560099795508;10051.246156538253;11244.38155901426;11778.587749428772;11815.70460738482;9325.5803488843;11406.258593191458;11407.973282404582;11427.314361138338;11440.970312671858;2438.4147804526874;2493.8628375439343;2494.6133349330885;2496.2636022373354;2497.0386401235564;2459.213051823417;2492.264781889338;2513.7234904160528;2514.635377870208;2520.6975162980466;2473.1591258971944;2481.8262721609494;2484.1553871683623;2490.7509249075038;2501.1371076978503;2440.3289095498703;2443.816713746467;2444.3033735385507;2447.730749617541;2452.681151450318;2081.55246621977;2355.845635638525;2382.826903720239;2406.3743426709007;2421.2010036687852;11459.541618335257;11668.241564298294;11675.94162029193;11689;11692.532298708042;5857.85355366115;6004.279486461609;6012.819615411545;6027.367126451703;6043.425874541911;3517.208628058692;3599.4420864766003;3608.0979755061235;3665.6334366563397;3670.0457880950876;1249.9812502812465;1252.5803855708348;1295.0481396173805;1300.7333496633169;1318.815365848782;16080.919595402065;16104.275307611186;16120.435784747595;16120.59698507535;16151.434699785452;4823.758812059383;4876.984776153413;4936.432310284292;4952.678075925053;4990;13431.597052088453;13543.932280338535;13555.796663050061;13557.796633050511;13581.11722738019;5083.796648134107;5952.434518720715;6028.397160284024;6045.969770151121;6059.818205453844;5002.649814513022;5015.22264049073;5290.444503327095;5290.81482148123;5377.8117765878005;3459.6713312235297;4028.0586574440736;4100.0774825664585;4130.545639979599;4214.3678448232395;1114.420501339299;1116.0736588631578;1121.5850135449937;1123.7583919457193;1130.8982191602631;948.8813898262717;965.8937516873139;981.877265341818;990.782027953849;997.8453339732353;6354.745810167541;6428.646424446701;6625.900611490832;6704.564203326866;6734.821406253984;905;906.977325566866;1007.5566750629699;1007.8337074382645;1024.2215915903967;4017.0760725033406;4074.9388759168637;4137.110521237948;4183.163367326486;4200.264953633102;13765.449382024806;14227.78658320126;14242.572722818331;14275.92862035704;14300.284985750672;13171.60485185446;14087.295635218197;14244.857551424353;14272.928635356964;14383.352749126314;10327.431991240554;10491.580336786443;10597.41714205726;10601.84097238542;10602.628907988183;6757.979002493508;6905.413039891465;7046.541974771623;7046.894296585453;7050.753223637148;12619.87380126187;12821.294828784134;12858.971282297582;12952.611421657366;12981.156224845356;11980.820287695517;13129.409176587267;13197.472101115847;13334.26661533625;13355.53255636048;9360.95319523398;9696.127348538665;9766.169875560658;9800.509974501389;9839.754006149904;10486.423246721504;10612.310199836986;10631.20265980039;10722.410267435365;10743.838842417526;9067.773305667411;9145.314101442551;9204.539773011456;9336;9363.297752668714;8909.55452227399;8935.642574296955;8950.418222815497;8980.775480612776;9165.679201227926;8053.919460805317;8059.1134975153855;8108.594570271581;8137.877931831145;8177.79555511117;5912.497437717842;5919.112133179885;5922.703864806656;6064.575479716554;6126.203593532812;4974;5043.596512279082;5921.822345329647;5946.048632218824;6024.367441418588 14:18:12 INFO - PROCESS | 1638 | |4;prototype.html;361.58959580875717;401.5482582095143;404.60955178253;405.16738103197923;409.26128338349275;4688.578027977485;4967.552920237178;4994.775235114419;5212.817551385702;5213.765380557875;4136.174039013915;4184.539700632928;5071.568916642081;5153.665011774241;5230.529252367286;3812.332841752696;3882.708796840227;4457.662701189646;4479.574440428154;4617.168909596271;4011.899702507446;4224.429701990222;4515.954840451586;4602.286645569942;4643.791029403685;3652.945205821915;3672.026912868092;4511.864644060667;4522.2312206924735;4557.908841823161;4498.752568608726;4537.682362234645;4541.72749635023;4564.338170965191;4585.592563903944;3761.9090463765747;3836.3094642964256;4276.5723427656985;4297.634701050414;4358.760268185249;4518.502964673916;4829.8024398898415;5027.245913113025;5075.842766824085;5174.379074511083;4704.129735998817;4721.551452611996;5111.284420181178;5300.727825321895;5306.575473962073;5407.61819180273;5613.663180209201;5652.954203472371;5681.545476361881;5696.202531645573;4148.751074935483;4819.614430845524;4836;4836.129496690594;4896.971635956454;5019.874503137415;5599.444352835938;5735.8566035849435;5768.673494878841;5865.187628041765;3966.960330396659;4788.760561971888;4977.701337919736;4977.950220497821;5006.424261209934;4544.431946006749;4897.853064408074;5089.949100509021;5115.411727651293;5120.462351453044;4497.6956682561695;5043.3191519145175;5092.236164575269;5199.7140157291715;5256.842294731164;4763.73799441034;5587.972060139673;5638.490871562116;5656.462636049569;5672;1531.5175719648275;1552.472159465787;1639.3606493467557;1696.2875592251285;1749.615084681368;1314.9151949641584;1340.4906135668384;1390.5133203378737;1397.3851505337618;1408.4718230663502;4866.8783280417565;5124.385073791169;5289.259503669489;5310.176922577007;5368.248445217672;5167.534921857051;5187.481251874782;5367.436419176009;5745.913811292834;5830.41695830422;4413.668974826836;4784.244368170969;4845.878853028632;5190.273361729361;5238.476152384807;2712.538868392378;2790.8696977724053;2795.3151477888046;2917.957429799149;3033.9393212135633;5150.742462876841;5458.235846981426;5665.960254308446;5739.077409068009;5972.492338151305;4885.656444477726;5655.830325090254;5724.484796368347;5896.646201227854;6014.51883849291;6099.69501524922;6120.969395153084;6129.2032035835055;6149.569530132937;6184.474319682788;4318.762468064161;5294.258803767552;5474.644148130359;5685.8443563822475;5694;4593.678442508993;4909.7545122743;5516.724163791875;5710.628809127394;5837.912431313619;5376.946230537645;5831.801103205685;6005.699715014144;6017.488513476228;6026.578139530367;4113.938290925698;4985.252212168204;4998.650094493496;5020.949790502048;5058.974705126451;5399.514043736082;5649.802256920988;5709.802510594081;5796.913046304225;5822.7000334868335;4546.4544254689645;4958.925616115834;5023.3125277976105;5072.554322018207;5558.694271815089;4852.204684266844;5568.860778480571;5601.60788744784;5627.971860140673;5637.49262566371;4431;4494.865154045384;5478.589105817;5577;5642.520385767256;5660.29246344207;5693.829185124453;5704.828855134353;5752.511036561939;5770.682612456355;4713.9292910607055;4910;5266.8419947401635;5500.654482958418;5556.249906262603 14:18:12 INFO - PROCESS | 1638 | |5;yui.html;759.529091962983;768.6886810841611;793.5516433215233;879.8460269452846;896.7713233125552;16009.359625614976;17448.91275543622;17817.821821781778;18034.729479057823;18083.095845207736;15847.683046339067;16317.592060198502;16334.34662613497;16381.42665006725;16424.425145119923;974.3277138774258;1001.8547310639952;1002.9097381235688;1003.0471052500118;1004.8593196952432;973.2894986659743;1002.3634991780235;1007.5516395204137;1016.425719468498;1027.7687520307945;1057.751428414322;1064.159314141827;1067.6423398161626;1069.3690722473739;1097.6706987903597;1047.5181416548396;1057.973550661232;1060.931039482431;1061.4427425601543;1062.6493257225136;682.7371461987121;784.2432053068759;786.3944762532875;789.4237206838982;791.3076058448858;15267.236638168048;16063.992000999875;17314.91342543292;18289.451316460396;18505.98217098059;14641.121532708072;14703.852961470355;14730.410783568752;14944.62638434037;15128;11882.702932426671;12182.878171218263;12192.451339689735;12223.205491643015;12393.93803030988;3860.9227815443815;3907.179492306619;3926.8429262829454;3962.8612998544913;4103.733257338294;118216.99999999914;120552;122901.99999999911;123070.38464807619;124558.37720811428;11315.603953861575;11569.819336887176;11655.242409243372;11759.706007349885;12134.393280335948;23971.520569588858;24131.517369652858;24355.391115221908;24601.876990614932;24718.752812472005;8183.6726530938095;8205.097439281673;8215.34277257818;8224.095349511548;8294.543800090933;4016.5581786003518;4535.727856328631;4579.22153233951;4599.632029437637;4852.320675105488;3666.211764470652;3708.35103856824;3724.1434470071495;3731.3470142724914;3746.38184699527;641.425923798198;653.833272515515;666.1074160624786;668.0346898731384;668.759246671198;672.4452326830286;703.2124021096405;708.7129712466459;726.0379996504653;745.1244787374812;9326.813463730687;10256.487175641338;10312.845307320396;10476.685699428877;10631.7773456052;649.331188875459;682.9516691877943;719.1370355573333;719.8164468060611;735.8381156145639;5135.948640513547;5177.064343230162;5193.246979187997;5303.390110137307;5321.680699158062;84110.57944710237;105554;106142.46928765306;106692.9330706683;107071.46464267629;62235.44411389661;71338;95730.9999999986;96452;97020;84949;85695.14304856872;86132.56933715292;86506.56746716225;88039;10014.59941602357;10184.338018028804;10204;10251.12865406419;10282.485875706036;52499.68750781261;52829;53506.66233344198;53765.73117134389;53903.730481347346;64715.029274561864;64763;65791;65949.67025164844;66193;9148.542572871196;11652.242604230698;11694.122940779305;11823.527058917547;12284.508619655113;14555.708885822436;15622.984506007073;15792.92103539475;16006.279717412977;16127.354905803637;14466.132032078109;14546.927265363605;14621.342039608457;14636.341364638403;14721.92639036798;14276.785848212492;14302.427902883768;14690.41238350454;14886.181260030802;15205.08769473791;13807.86192138106;14538.854611454173;14730.337134829173;14753.409863605335;14883.47907823221;10677.89322106769;10686.786264274826;10818.67543973682;11155.051820595016;11404.657860264206;11342.319460832376;11661.125415593695;12123.969007748063;12148.21036632616;12220.511179553074 14:18:12 INFO - PROCESS | 1638 | __end_tp_report 14:18:12 INFO - PROCESS | 1638 | __start_cc_report 14:18:12 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,396 14:18:12 INFO - PROCESS | 1638 | __end_cc_report 14:18:12 INFO - PROCESS | 1638 | __startTimestamp1495574292618__endTimestamp 14:18:12 INFO - PROCESS | 1638 | 14:18:12 INFO - PROCESS | 1638 | ------- Summary: start ------- 14:18:12 INFO - PROCESS | 1638 | Number of tests: 6 14:18:12 INFO - PROCESS | 1638 | 14:18:12 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:6139.68 Median:6326.91 stddev:667.83 (10.6%) stddev-sans-first:640.71 14:18:12 INFO - PROCESS | 1638 | Values: 3651.0 3654.1 3661.5 3668.2 3687.6 5931.3 6195.8 6247.0 6269.2 6274.8 6194.9 6224.9 6228.1 6280.4 6291.4 4665.9 5682.3 5702.4 5709.5 5761.7 5731.8 5735.5 5746.4 5754.6 5771.0 5543.4 5549.1 5741.4 5749.8 5814.7 5665.4 5676.7 5736.1 5740.4 5750.5 5520.2 5622.2 5648.7 5654.1 5696.3 5988.7 6024.4 6206.5 6215.5 6281.1 6417.5 6419.1 6497.4 6501.7 6579.3 6756.7 6757.2 6761.2 6779.2 6783.1 6301.9 6308.3 6355.7 6365.6 6370.8 6549.7 6605.1 6611.2 6749.7 6857.9 6503.8 6508.8 6538.0 6556.3 6557.4 6633.9 6664.7 6697.1 6697.4 6723.4 6341.5 6355.2 6551.0 6567.7 6592.1 5492.9 6337.0 6505.2 6519.5 6539.3 4571.5 5259.8 5278.3 5290.0 5305.5 4709.8 4718.3 4727.7 4739.2 4749.3 6134.3 6160.8 6212.8 6295.9 6312.9 5757.0 6347.0 6650.1 6652.7 6694.9 6266.5 6269.3 6304.9 6315.7 6326.9 5649.0 5735.6 5872.4 5877.4 5894.4 6710.6 6773.5 6801.2 6805.6 6814.0 6586.1 6679.3 6695.0 6714.0 6725.1 6865.1 6865.9 6878.6 6878.9 6895.1 6344.9 6402.9 6491.6 6530.6 6549.3 6660.3 6661.6 6671.4 6672.3 6692.7 5755.3 6423.9 6486.4 6503.8 6520.3 6270.8 6337.1 6404.3 6407.8 6444.2 6263.9 6340.6 6357.4 6359.6 6400.2 6262.5 6504.9 6515.2 6519.4 6543.9 6165.8 6207.5 6249.8 6287.3 6312.1 14:18:12 INFO - PROCESS | 1638 | 14:18:12 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:54005.90 Median:18419.92 stddev:103344.81 (561.0%) stddev-sans-first:103557.46 14:18:12 INFO - PROCESS | 1638 | Values: 784.9 835.6 854.0 894.5 992.9 39791.4 40147.0 41209.6 41341.8 41479.0 40096.4 40291.0 40369.4 40407.0 40421.0 8276.6 11156.5 11409.1 11630.4 11649.7 11292.8 11376.8 11377.2 11395.5 11410.4 11366.6 11389.5 11402.3 11404.0 11426.8 11276.6 11341.9 11352.5 11363.6 11364.5 9328.0 9426.5 10961.3 11044.8 11079.6 35627.6 35694.9 35783.6 35787.5 35904.6 4006.6 4026.2 4049.5 4068.8 4088.0 26997.3 28026.3 28202.6 28281.4 28310.9 3111.9 3307.1 3339.8 3410.1 3411.1 338482.0 340448.3 348975.0 349023.0 372860.0 45035.8 45192.3 45314.3 45349.3 45405.1 46507.3 46884.8 47003.3 47164.5 47212.5 14116.3 14121.6 14230.4 14301.3 14304.8 12376.1 12499.3 12620.9 12623.9 12640.4 12155.9 12163.8 12173.3 12218.1 12295.2 5752.7 5793.1 5804.1 5821.9 5836.0 1021.6 1063.6 1081.9 1160.6 1168.5 22694.7 23287.3 25300.2 26946.7 27421.0 1062.4 1252.4 1276.5 1282.6 1311.5 11570.5 13989.7 14027.1 14219.8 14252.9 415167.0 418510.0 420057.0 420418.0 420964.0 56360.4 56378.0 56724.4 56756.7 56798.7 1476.6 1491.9 1519.6 1527.9 1531.9 25327.9 26568.7 26960.0 26960.6 26981.0 45148.3 45624.8 45625.0 45712.3 45949.8 399261.0 402285.0 403008.0 403459.0 403674.0 27715.6 28173.9 31691.4 31842.7 32628.0 45244.3 45992.3 46178.3 46844.9 47363.3 31603.0 31755.0 31785.5 32045.7 32404.4 25827.5 30859.2 31371.9 31743.5 31809.5 37795.9 37889.8 43469.3 43668.3 45641.6 39651.6 42230.8 42270.6 43198.6 43415.0 32192.7 33500.0 33634.7 33687.3 33755.3 14:18:12 INFO - PROCESS | 1638 | 14:18:12 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:43960.59 Median:21778.78 stddev:125638.14 (576.9%) stddev-sans-first:125948.05 14:18:12 INFO - PROCESS | 1638 | Values: 422.3 432.3 444.3 444.9 455.8 21095.9 21521.2 22250.3 22311.0 22407.0 17340.6 22088.9 22487.6 22666.4 22861.9 6310.9 6677.5 6703.6 6965.5 6978.6 6779.7 6872.2 6934.7 6946.4 6947.5 5888.4 6624.4 6920.6 6931.4 6943.6 6448.3 6735.2 6791.3 6799.5 6918.7 6560.8 6561.0 6568.9 6575.4 6597.8 20913.9 26022.0 26298.7 26434.6 26664.9 3053.3 3055.0 3056.2 3072.3 3095.3 15988.8 16763.6 16821.9 16940.7 16990.3 2110.7 2127.7 2129.2 2129.3 2130.1 137581.0 137909.3 156753.0 159477.4 187988.1 20164.2 20265.7 20282.3 20410.0 20595.7 29859.7 30072.0 30387.1 30474.4 30486.5 10236.4 10250.4 10291.6 10417.1 10625.3 8802.4 8802.6 8806.8 8938.5 9022.8 9110.7 9163.3 9184.5 9305.4 9323.7 4222.7 4287.5 4303.2 4320.4 4339.7 1037.6 1063.1 1075.1 1099.1 1109.8 21509.1 21775.7 21781.9 21868.8 21949.5 1093.8 1115.1 1123.6 1133.5 1139.1 9753.9 11010.2 11249.4 11281.8 11330.6 683546.0 754443.0 755136.0 765502.0 784077.0 166635.0 169100.0 169242.9 172033.0 173768.3 1210.6 1239.9 1253.6 1256.5 1257.8 18579.3 18655.9 19119.2 19126.5 19143.7 62280.4 69439.0 69544.7 71642.6 71755.9 85232.0 97302.0 97717.0 98703.5 99794.0 11368.8 11708.9 11790.6 11850.2 12045.2 12771.4 12950.7 12956.9 13261.8 13342.3 9364.6 10337.8 11444.9 11495.8 11564.2 9851.3 10350.8 10414.8 10569.1 10916.7 9926.9 11607.4 11778.3 11805.1 11816.9 11550.9 11648.4 11717.8 11737.5 11989.9 9571.4 11287.2 11295.0 11549.8 11557.0 14:18:12 INFO - PROCESS | 1638 | 14:18:12 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6983.24 Median:2496.65 stddev:4453.13 (178.4%) stddev-sans-first:4446.20 14:18:12 INFO - PROCESS | 1638 | Values: 1448.9 1589.3 1657.2 1660.4 1660.4 9775.6 10051.2 11244.4 11778.6 11815.7 9325.6 11406.3 11408.0 11427.3 11441.0 2438.4 2493.9 2494.6 2496.3 2497.0 2459.2 2492.3 2513.7 2514.6 2520.7 2473.2 2481.8 2484.2 2490.8 2501.1 2440.3 2443.8 2444.3 2447.7 2452.7 2081.6 2355.8 2382.8 2406.4 2421.2 11459.5 11668.2 11675.9 11689.0 11692.5 5857.9 6004.3 6012.8 6027.4 6043.4 3517.2 3599.4 3608.1 3665.6 3670.0 1250.0 1252.6 1295.0 1300.7 1318.8 16080.9 16104.3 16120.4 16120.6 16151.4 4823.8 4877.0 4936.4 4952.7 4990.0 13431.6 13543.9 13555.8 13557.8 13581.1 5083.8 5952.4 6028.4 6046.0 6059.8 5002.6 5015.2 5290.4 5290.8 5377.8 3459.7 4028.1 4100.1 4130.5 4214.4 1114.4 1116.1 1121.6 1123.8 1130.9 948.9 965.9 981.9 990.8 997.8 6354.7 6428.6 6625.9 6704.6 6734.8 905.0 907.0 1007.6 1007.8 1024.2 4017.1 4074.9 4137.1 4183.2 4200.3 13765.4 14227.8 14242.6 14275.9 14300.3 13171.6 14087.3 14244.9 14272.9 14383.4 10327.4 10491.6 10597.4 10601.8 10602.6 6758.0 6905.4 7046.5 7046.9 7050.8 12619.9 12821.3 12859.0 12952.6 12981.2 11980.8 13129.4 13197.5 13334.3 13355.5 9361.0 9696.1 9766.2 9800.5 9839.8 10486.4 10612.3 10631.2 10722.4 10743.8 9067.8 9145.3 9204.5 9336.0 9363.3 8909.6 8935.6 8950.4 8980.8 9165.7 8053.9 8059.1 8108.6 8137.9 8177.8 5912.5 5919.1 5922.7 6064.6 6126.2 4974.0 5043.6 5921.8 5946.0 6024.4 14:18:12 INFO - PROCESS | 1638 | 14:18:12 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4721.14 Median:5051.15 stddev:1282.96 (25.4%) stddev-sans-first:1244.13 14:18:12 INFO - PROCESS | 1638 | Values: 361.6 401.5 404.6 405.2 409.3 4688.6 4967.6 4994.8 5212.8 5213.8 4136.2 4184.5 5071.6 5153.7 5230.5 3812.3 3882.7 4457.7 4479.6 4617.2 4011.9 4224.4 4516.0 4602.3 4643.8 3652.9 3672.0 4511.9 4522.2 4557.9 4498.8 4537.7 4541.7 4564.3 4585.6 3761.9 3836.3 4276.6 4297.6 4358.8 4518.5 4829.8 5027.2 5075.8 5174.4 4704.1 4721.6 5111.3 5300.7 5306.6 5407.6 5613.7 5653.0 5681.5 5696.2 4148.8 4819.6 4836.0 4836.1 4897.0 5019.9 5599.4 5735.9 5768.7 5865.2 3967.0 4788.8 4977.7 4978.0 5006.4 4544.4 4897.9 5089.9 5115.4 5120.5 4497.7 5043.3 5092.2 5199.7 5256.8 4763.7 5588.0 5638.5 5656.5 5672.0 1531.5 1552.5 1639.4 1696.3 1749.6 1314.9 1340.5 1390.5 1397.4 1408.5 4866.9 5124.4 5289.3 5310.2 5368.2 5167.5 5187.5 5367.4 5745.9 5830.4 4413.7 4784.2 4845.9 5190.3 5238.5 2712.5 2790.9 2795.3 2918.0 3033.9 5150.7 5458.2 5666.0 5739.1 5972.5 4885.7 5655.8 5724.5 5896.6 6014.5 6099.7 6121.0 6129.2 6149.6 6184.5 4318.8 5294.3 5474.6 5685.8 5694.0 4593.7 4909.8 5516.7 5710.6 5837.9 5376.9 5831.8 6005.7 6017.5 6026.6 4113.9 4985.3 4998.7 5020.9 5059.0 5399.5 5649.8 5709.8 5796.9 5822.7 4546.5 4958.9 5023.3 5072.6 5558.7 4852.2 5568.9 5601.6 5628.0 5637.5 4431.0 4494.9 5478.6 5577.0 5642.5 5660.3 5693.8 5704.8 5752.5 5770.7 4713.9 4910.0 5266.8 5500.7 5556.2 14:18:12 INFO - PROCESS | 1638 | 14:18:12 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:21518.62 Median:16357.89 stddev:30903.30 (188.9%) stddev-sans-first:30950.68 14:18:12 INFO - PROCESS | 1638 | Values: 759.5 768.7 793.6 879.8 896.8 16009.4 17448.9 17817.8 18034.7 18083.1 15847.7 16317.6 16334.3 16381.4 16424.4 974.3 1001.9 1002.9 1003.0 1004.9 973.3 1002.4 1007.6 1016.4 1027.8 1057.8 1064.2 1067.6 1069.4 1097.7 1047.5 1058.0 1060.9 1061.4 1062.6 682.7 784.2 786.4 789.4 791.3 15267.2 16064.0 17314.9 18289.5 18506.0 14641.1 14703.9 14730.4 14944.6 15128.0 11882.7 12182.9 12192.5 12223.2 12393.9 3860.9 3907.2 3926.8 3962.9 4103.7 118217.0 120552.0 122902.0 123070.4 124558.4 11315.6 11569.8 11655.2 11759.7 12134.4 23971.5 24131.5 24355.4 24601.9 24718.8 8183.7 8205.1 8215.3 8224.1 8294.5 4016.6 4535.7 4579.2 4599.6 4852.3 3666.2 3708.4 3724.1 3731.3 3746.4 641.4 653.8 666.1 668.0 668.8 672.4 703.2 708.7 726.0 745.1 9326.8 10256.5 10312.8 10476.7 10631.8 649.3 683.0 719.1 719.8 735.8 5135.9 5177.1 5193.2 5303.4 5321.7 84110.6 105554.0 106142.5 106692.9 107071.5 62235.4 71338.0 95731.0 96452.0 97020.0 84949.0 85695.1 86132.6 86506.6 88039.0 10014.6 10184.3 10204.0 10251.1 10282.5 52499.7 52829.0 53506.7 53765.7 53903.7 64715.0 64763.0 65791.0 65949.7 66193.0 9148.5 11652.2 11694.1 11823.5 12284.5 14555.7 15623.0 15792.9 16006.3 16127.4 14466.1 14546.9 14621.3 14636.3 14721.9 14276.8 14302.4 14690.4 14886.2 15205.1 13807.9 14538.9 14730.3 14753.4 14883.5 10677.9 10686.8 10818.7 11155.1 11404.7 11342.3 11661.1 12124.0 12148.2 12220.5 14:18:12 INFO - PROCESS | 1638 | -------- Summary: end -------- 14:18:12 INFO - PROCESS | 1638 | 14:18:13 INFO - TEST-INFO | 1638: exit 0 14:18:13 INFO - TEST-OK | dromaeo_css | took 1115031ms 14:18:13 INFO - TEST-START | kraken 14:18:13 INFO - operating with platform_type : mac_ 14:18:13 INFO - Initialising browser for kraken test... 14:18:13 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpSUXv6r/profile http://localhost:49218/getInfo.html) 14:18:15 INFO - PROCESS | 1648 | 2017-05-23 14:18:15.582 plugin-container[1650:10064] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 14:18:15 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:18:15 INFO - PROCESS | 1648 | 2017-05-23 14:18:15.583 plugin-container[1650:10064] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 14:18:15 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:18:15 INFO - PROCESS | 1648 | 2017-05-23 14:18:15.583 plugin-container[1650:10064] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:18:15 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 14:18:15 INFO - PROCESS | 1648 | colorDepth:24 14:18:15 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 14:18:15 INFO - PROCESS | 1648 | __metrics 14:18:15 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 14:18:16 INFO - TEST-INFO | 1648: exit 0 14:18:16 INFO - Browser initialized. 14:18:16 INFO - Running cycle 1/1 for kraken test... 14:18:16 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpSUXv6r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:18:18 INFO - PROCESS | 1651 | 2017-05-23 14:18:18.602 plugin-container[1652:10191] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 14:18:18 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:18:18 INFO - PROCESS | 1651 | 2017-05-23 14:18:18.603 plugin-container[1652:10191] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8723, name = 'com.apple.CFPasteboardClient' 14:18:18 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:18:18 INFO - PROCESS | 1651 | 2017-05-23 14:18:18.603 plugin-container[1652:10191] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:18:28 INFO - PROCESS | 1651 | RSS: Main: 174264320 14:18:28 INFO - PROCESS | 1651 | 14:18:31 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-beat-detection) 14:18:32 INFO - PROCESS | 1651 | RSS: Main: 187719680 14:18:32 INFO - PROCESS | 1651 | 14:18:34 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-dft) 14:18:35 INFO - PROCESS | 1651 | RSS: Main: 195739648 14:18:35 INFO - PROCESS | 1651 | 14:18:38 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-fft) 14:18:39 INFO - PROCESS | 1651 | RSS: Main: 199462912 14:18:39 INFO - PROCESS | 1651 | 14:18:41 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-oscillator) 14:18:42 INFO - PROCESS | 1651 | RSS: Main: 199819264 14:18:42 INFO - PROCESS | 1651 | 14:18:44 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49218/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:18:44 INFO - PROCESS | 1651 | RSS: Main: 201297920 14:18:44 INFO - PROCESS | 1651 | 14:18:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49218/tests/kraken/driver.html?testName=imaging-darkroom) 14:18:48 INFO - PROCESS | 1651 | RSS: Main: 205393920 14:18:48 INFO - PROCESS | 1651 | 14:18:51 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49218/tests/kraken/driver.html?testName=imaging-desaturate) 14:18:52 INFO - PROCESS | 1651 | RSS: Main: 204546048 14:18:52 INFO - PROCESS | 1651 | 14:18:55 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49218/tests/kraken/driver.html?testName=json-parse-financial) 14:18:55 INFO - PROCESS | 1651 | RSS: Main: 204754944 14:18:55 INFO - PROCESS | 1651 | 14:18:57 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49218/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:18:58 INFO - PROCESS | 1651 | RSS: Main: 204115968 14:18:58 INFO - PROCESS | 1651 | 14:19:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:19:00 INFO - PROCESS | 1651 | RSS: Main: 204169216 14:19:00 INFO - PROCESS | 1651 | 14:19:02 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:19:03 INFO - PROCESS | 1651 | RSS: Main: 204140544 14:19:03 INFO - PROCESS | 1651 | 14:19:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:19:07 INFO - PROCESS | 1651 | RSS: Main: 204648448 14:19:07 INFO - PROCESS | 1651 | 14:19:10 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:19:10 INFO - PROCESS | 1651 | RSS: Main: 204394496 14:19:10 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49218/tests/kraken/driver.html?testName=ai-astar) 14:19:12 INFO - PROCESS | 1651 | RSS: Main: 206102528 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | __start_tp_report 14:19:12 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 14:19:12 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 14:19:12 INFO - PROCESS | 1651 | |i|pagename|runs| 14:19:12 INFO - PROCESS | 1651 | |0;ai-astar;107;120;118;121;100;174;174;124;176;174 14:19:12 INFO - PROCESS | 1651 | |1;audio-beat-detection;141;109;110;140;110;110;111;137;129;110 14:19:12 INFO - PROCESS | 1651 | |2;audio-dft;202;204;232;208;218;200;196;195;194;212 14:19:12 INFO - PROCESS | 1651 | |3;audio-fft;71;83;85;101;63;59;66;86;64;88 14:19:12 INFO - PROCESS | 1651 | |4;audio-oscillator;84;71;84;70;70;70;81;69;70;82 14:19:12 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;117;119;136;118;88;99;88;89;88;88 14:19:12 INFO - PROCESS | 1651 | |6;imaging-darkroom;131;143;95;102;152;152;95;95;105;127 14:19:12 INFO - PROCESS | 1651 | |7;imaging-desaturate;90;100;91;141;135;90;88;92;120;81 14:19:12 INFO - PROCESS | 1651 | |8;json-parse-financial;65;64;66;67;69;68;69;68;68;67 14:19:12 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;57;61;57;71;67;65;60;59;62;61 14:19:12 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;84;76;86;101;101;99;100;82;86;81 14:19:12 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;129;133;167;147;137;143;151;131;189;143 14:19:12 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;163;165;169;159;202;166;163;170;164;163 14:19:12 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;60;73;84;84;62;61;81;84;81;75 14:19:12 INFO - PROCESS | 1651 | __end_tp_report 14:19:12 INFO - PROCESS | 1651 | __start_cc_report 14:19:12 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,995 14:19:12 INFO - PROCESS | 1651 | __end_cc_report 14:19:12 INFO - PROCESS | 1651 | __startTimestamp1495574352821__endTimestamp 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | ------- Summary: start ------- 14:19:12 INFO - PROCESS | 1651 | Number of tests: 14 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:138.80 Median:149.00 stddev:31.52 (21.2%) stddev-sans-first:31.26 14:19:12 INFO - PROCESS | 1651 | Values: 107.0 120.0 118.0 121.0 100.0 174.0 174.0 124.0 176.0 174.0 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:120.70 Median:120.00 stddev:14.17 (11.8%) stddev-sans-first:12.99 14:19:12 INFO - PROCESS | 1651 | Values: 141.0 109.0 110.0 140.0 110.0 110.0 111.0 137.0 129.0 110.0 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:206.10 Median:206.00 stddev:11.93 (5.8%) stddev-sans-first:12.56 14:19:12 INFO - PROCESS | 1651 | Values: 202.0 204.0 232.0 208.0 218.0 200.0 196.0 195.0 194.0 212.0 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:76.60 Median:77.00 stddev:13.83 (18.0%) stddev-sans-first:14.52 14:19:12 INFO - PROCESS | 1651 | Values: 71.0 83.0 85.0 101.0 63.0 59.0 66.0 86.0 64.0 88.0 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:75.10 Median:76.00 stddev:6.66 (8.8%) stddev-sans-first:6.23 14:19:12 INFO - PROCESS | 1651 | Values: 84.0 71.0 84.0 70.0 70.0 70.0 81.0 69.0 70.0 82.0 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:103.00 Median:88.00 stddev:17.88 (20.3%) stddev-sans-first:18.24 14:19:12 INFO - PROCESS | 1651 | Values: 117.0 119.0 136.0 118.0 88.0 99.0 88.0 89.0 88.0 88.0 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:119.70 Median:152.00 stddev:23.97 (15.8%) stddev-sans-first:25.07 14:19:12 INFO - PROCESS | 1651 | Values: 131.0 143.0 95.0 102.0 152.0 152.0 95.0 95.0 105.0 127.0 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:102.80 Median:89.00 stddev:21.29 (23.9%) stddev-sans-first:22.07 14:19:12 INFO - PROCESS | 1651 | Values: 90.0 100.0 91.0 141.0 135.0 90.0 88.0 92.0 120.0 81.0 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:67.10 Median:68.00 stddev:1.66 (2.4%) stddev-sans-first:1.58 14:19:12 INFO - PROCESS | 1651 | Values: 65.0 64.0 66.0 67.0 69.0 68.0 69.0 68.0 68.0 67.0 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:62.00 Median:61.50 stddev:4.47 (7.3%) stddev-sans-first:4.36 14:19:12 INFO - PROCESS | 1651 | Values: 57.0 61.0 57.0 71.0 67.0 65.0 60.0 59.0 62.0 61.0 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:83.00 stddev:9.61 (11.6%) stddev-sans-first:9.97 14:19:12 INFO - PROCESS | 1651 | Values: 84.0 76.0 86.0 101.0 101.0 99.0 100.0 82.0 86.0 81.0 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:145.00 stddev:18.52 (12.8%) stddev-sans-first:18.47 14:19:12 INFO - PROCESS | 1651 | Values: 129.0 133.0 167.0 147.0 137.0 143.0 151.0 131.0 189.0 143.0 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:165.50 stddev:12.22 (7.4%) stddev-sans-first:12.81 14:19:12 INFO - PROCESS | 1651 | Values: 163.0 165.0 169.0 159.0 202.0 166.0 163.0 170.0 164.0 163.0 14:19:12 INFO - PROCESS | 1651 | 14:19:12 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.50 Median:81.00 stddev:10.04 (12.4%) stddev-sans-first:9.17 14:19:12 INFO - PROCESS | 1651 | Values: 60.0 73.0 84.0 84.0 62.0 61.0 81.0 84.0 81.0 75.0 14:19:12 INFO - PROCESS | 1651 | -------- Summary: end -------- 14:19:12 INFO - PROCESS | 1651 | 14:19:13 INFO - TEST-INFO | 1651: exit 0 14:19:13 INFO - TEST-OK | kraken | took 60619ms 14:19:13 INFO - SUITE-END | took 1175s 14:19:13 INFO - Completed test suite (00:19:35) 14:19:13 INFO - javascript benchmark 14:19:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7981.020756288076, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3650.9634903650963, 3654.068212605785, 3661.505696730942, 3668.192997540542, 3687.612800655932, 5931.347551769302, 6195.783147589836, 6247.031710084939, 6269.247690277173, 6274.811755647333, 6194.907076393858, 6224.875502489947, 6228.096925945735, 6280.434760871521, 6291.40231677991, 4665.88335291617, 5682.346530149024, 5702.372738998707, 5709.486146246837, 5761.683107429089, 5731.828045158652, 5735.4551317624755, 5746.367899531048, 5754.568407369443, 5771.047777116771, 5543.417941116185, 5549.056660367747, 5741.425857414246, 5749.798757043504, 5814.651120932757, 5665.3768085510565, 5676.659400435987, 5736.110902810071, 5740.425957404268, 5750.511206547449, 5520.227168196456, 5622.156676498517, 5648.717564121818, 5654.095344744821, 5696.3449203341315, 5988.700564971778, 6024.397560243984, 6206.472449841769, 6215.502759779252, 6281.089242059921, 6417.518686098515, 6419.133416988693, 6497.447716944065, 6501.707423165969, 6579.34206579343, 6756.729730810762, 6757.155355580552, 6761.222459417181, 6779.186497620317, 6783.084283621697, 6301.905471417932, 6308.300788537544, 6355.713992870378, 6365.554411191219, 6370.777022804226, 6549.705263263165, 6605.108310378134, 6611.206655201407, 6749.730010799513, 6857.931420685729, 6503.804885853432, 6508.772192973223, 6537.967310163419, 6556.344365563501, 6557.442617377576, 6633.938569828804, 6664.666766661648, 6697.129373181553, 6697.430718388894, 6723.361280678328, 6341.524385671093, 6355.205599300088, 6550.967245163743, 6567.737290508326, 6592.143021407186, 5492.862678433071, 6337.0, 6505.154329937172, 6519.511036672268, 6539.313372095957, 4571.451425828922, 5259.790248242925, 5278.261043453919, 5289.973550132302, 5305.549028332635, 4709.764511774398, 4718.292256161603, 4727.739974301447, 4739.170645137087, 4749.2876068589985, 6134.314131579348, 6160.815175544741, 6212.751489940442, 6295.937040629627, 6312.936870631327, 5756.971215143897, 6346.984482482781, 6650.135482387259, 6652.73389064432, 6694.866102678016, 6266.49868010558, 6269.341719119518, 6304.936950630527, 6315.747370105236, 6326.905096423466, 5649.0114230009585, 5735.627184233012, 5872.412758724179, 5877.4122587740885, 5894.410558944071, 6710.597364158166, 6773.458123350219, 6801.18385793708, 6805.625690587065, 6813.965930170318, 6586.077949087035, 6679.265280819009, 6695.029220763059, 6713.966430167818, 6725.125733654593, 6865.0732151160955, 6865.862682746417, 6878.58728476293, 6878.931210687829, 6895.138107736533, 6344.873102537831, 6402.935970640234, 6491.610503369813, 6530.6081635100145, 6549.279579246184, 6660.2673705893285, 6661.633610151488, 6671.366220209073, 6672.266050734318, 6692.665366731741, 5755.3381361144, 6423.871522569429, 6486.383793539606, 6503.772367967098, 6520.250171230369, 6270.843228919313, 6337.112804207507, 6404.295527492065, 6407.775727849503, 6444.226692796707, 6263.874722505616, 6340.556161068681, 6357.364263573791, 6359.618422894641, 6400.23197216337, 6262.4990000800735, 6504.869902602016, 6515.218173819172, 6519.445847103049, 6543.934560654333, 6165.84585385351, 6207.503399727921, 6249.843753906007, 6287.339829318039, 6312.147860038836], "value": 6098.737026625542, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.8587254294229, 835.6448509383513, 853.9573021348933, 894.4946105450424, 992.895745946676, 39791.403128953054, 40147.0, 41209.58790412095, 41341.793291033544, 41478.963025924364, 40096.39855402171, 40290.99272518189, 40369.39445908314, 40407.0, 40420.98947526306, 8276.627551760184, 11156.497957591888, 11409.144314176428, 11630.360330181837, 11649.650510484698, 11292.774144517105, 11376.772464550704, 11377.2035957483, 11395.54417823286, 11410.429478526083, 11366.60216892409, 11389.487473063731, 11402.315861048322, 11404.0, 11426.771464570704, 11276.605318813843, 11341.886581134208, 11352.48913798881, 11363.602273920415, 11364.545418183263, 9328.0, 9426.481543515103, 10961.342319460859, 11044.779104417947, 11079.556817727282, 35627.643723562695, 35694.92915212548, 35783.64216357829, 35787.46318805194, 35904.64095359065, 4006.639402453793, 4026.1545075534173, 4049.514058312992, 4068.7965601719798, 4088.0, 26997.325066873287, 28026.299342516395, 28202.576961345596, 28281.434371312665, 28310.86756529736, 3111.937761244785, 3307.1236122427576, 3339.833008349597, 3410.0963244740165, 3411.096059544223, 338482.0, 340448.29775850964, 348975.0, 349023.0, 372859.99999999726, 45035.774821125684, 45192.322115168296, 45314.32028519575, 45349.31976020362, 45405.091898162515, 46507.30239046417, 46884.7655761719, 47003.29495057577, 47164.528354716014, 47212.5278747215, 14116.294185290695, 14121.576352709435, 14230.43078276878, 14301.28493575317, 14304.784093352182, 12376.133670643148, 12499.312537810507, 12620.927221186119, 12623.926966207791, 12640.367981600883, 12155.878441215475, 12163.756724865452, 12173.330466824409, 12218.083643726755, 12295.200811947374, 5752.712364381848, 5793.102069179284, 5804.1293805929445, 5821.854453638693, 5835.9708201458725, 1021.6219998600566, 1063.6011495689118, 1081.9342947197006, 1160.6053941659877, 1168.4741866160261, 22694.659580106312, 23287.301380958597, 25300.240992770247, 26946.702394674747, 27421.0, 1062.4422178356424, 1252.3988485526997, 1276.508544210467, 1282.570338936448, 1311.4623004568082, 11570.479328430241, 13989.740923316951, 14027.08823926462, 14219.786703199461, 14252.857471425154, 415167.0, 418510.0, 420057.0, 420418.0, 420964.0, 56360.43639563634, 56378.0, 56724.432755672744, 56756.71621641865, 56798.7160064197, 1476.6234610174552, 1491.8731907787742, 1519.6124988128179, 1527.900686455377, 1531.8851086168581, 25327.873360633817, 26568.734312656627, 26960.0, 26960.595591066543, 26981.0, 45148.32277515905, 45624.771876141735, 45625.0, 45712.31431528463, 45949.770251149865, 399261.0, 402284.98857505526, 403008.0, 403459.0, 403674.0, 27715.58426623562, 28173.859130705034, 31691.366172676877, 31842.68157318397, 32628.0, 45244.32133517934, 45992.31011534896, 46178.307325389476, 46844.865251277035, 47363.28955065746, 31603.0, 31755.0, 31785.523217152222, 32045.67954320427, 32404.35191296208, 25827.483450330514, 30859.228519287197, 31371.90198343047, 31743.52384714185, 31809.5228571567, 37795.86612401632, 37889.81055094707, 43469.347959779996, 43668.34497482604, 45641.63075107752, 39651.60348396479, 42230.7888460568, 42270.57729422666, 43198.568014320714, 43415.0, 32192.678073219904, 33500.0, 33634.66365336315, 33687.32625347529, 33755.3248935015], "value": 19310.320831409375, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.25050535299846, 432.250046169895, 444.291355288315, 444.882106241846, 455.8472911574622, 21095.894520527396, 21521.24675636353, 22250.332490025325, 22310.996005179764, 22407.0, 17340.56648583786, 22088.895555222254, 22487.55024899501, 22666.433339166528, 22861.885690571566, 6310.927142385805, 6677.465802735769, 6703.564268322568, 6965.5124141310125, 6978.581285122884, 6779.661016949158, 6872.175338959307, 6934.72261109555, 6946.374826265635, 6947.548409353376, 5888.440598143169, 6624.370684784938, 6920.584764914096, 6931.410830079424, 6943.583384996891, 6448.258450278231, 6735.22544907337, 6791.252962174157, 6799.52403331767, 6918.688659010331, 6560.7703730369685, 6561.015847622847, 6568.901466478007, 6575.441087507567, 6597.80206593803, 20913.8954305229, 26022.0, 26298.737012630012, 26434.6034809478, 26664.86667566669, 3053.267215868198, 3054.9541756873664, 3056.2206637307568, 3072.308730535634, 3095.349976504937, 15988.800839937052, 16763.580910477212, 16821.906576072517, 16940.745888811678, 16990.320387184496, 2110.7361579802523, 2127.744670639533, 2129.169623846701, 2129.2973318804757, 2130.0627723801476, 137581.0, 137909.31045344708, 156753.0, 159477.4052259474, 187988.06005969882, 20164.19343226284, 20265.696014559795, 20282.290119845733, 20409.97950102489, 20595.691064634044, 29859.70140298569, 30072.0, 30387.088387347972, 30474.39051218963, 30486.54270185949, 10236.436995965294, 10250.436226007492, 10291.639792607222, 10417.06246437809, 10625.256232063835, 8802.427842190236, 8802.647894084294, 8806.779830504289, 8938.46369217849, 9022.819543609092, 9110.681126160685, 9163.31275154366, 9184.540772961325, 9305.441673499476, 9323.72028839136, 4222.662187025031, 4287.499375218711, 4303.160883627663, 4320.364577607656, 4339.7396156230725, 1037.6316407675317, 1063.1069901282806, 1075.0593230922943, 1099.1316859680921, 1109.7614012987085, 21509.13963441445, 21775.67336489954, 21781.891090544763, 21868.78131218699, 21949.451263718216, 1093.7593729379523, 1115.0967716149783, 1123.5618108937522, 1133.4672703829187, 1139.1228753859482, 9753.853692194481, 11010.229283950206, 11249.381284029294, 11281.774364512663, 11330.603428880115, 683546.0, 754443.0, 755136.0, 765502.0, 784077.0, 166635.0, 169100.0, 169242.92271231883, 172033.0, 173768.26231737522, 1210.6489118155812, 1239.8884100431005, 1253.5988483685317, 1256.5162412471263, 1257.836481257449, 18579.32786049262, 18655.90672046631, 19119.23523059062, 19126.521836954187, 19143.712844307625, 62280.44298892564, 69438.9584156228, 69544.6522767383, 71642.64178679073, 71755.92366114409, 85232.0, 97302.0269797293, 97717.0, 98703.50648246713, 99794.0, 11368.772624547628, 11708.88291117078, 11790.587329443428, 11850.229735067192, 12045.217060891015, 12771.425285862344, 12950.740985180055, 12956.870431295567, 13261.801072984106, 13342.332883355599, 9364.625414983597, 10337.793244134924, 11444.94277528607, 11495.75832712613, 11564.190506664789, 9851.261155413229, 10350.792984140426, 10414.843777343498, 10569.101626361538, 10916.672499825017, 9926.851097233402, 11607.36159511235, 11778.293302401884, 11805.114616403633, 11816.881831181921, 11550.884491155317, 11648.41757912118, 11717.765644686888, 11737.530498780296, 11989.940050299692, 9571.37786043905, 11287.20989530725, 11295.0, 11549.769004619693, 11557.0], "value": 11809.507124061998, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.898577099603, 1589.3245370717443, 1657.1962598139908, 1660.3690597572927, 1660.4022551881317, 9775.560099795508, 10051.246156538253, 11244.38155901426, 11778.587749428772, 11815.70460738482, 9325.5803488843, 11406.258593191458, 11407.973282404582, 11427.314361138338, 11440.970312671858, 2438.4147804526874, 2493.8628375439343, 2494.6133349330885, 2496.2636022373354, 2497.0386401235564, 2459.213051823417, 2492.264781889338, 2513.7234904160528, 2514.635377870208, 2520.6975162980466, 2473.1591258971944, 2481.8262721609494, 2484.1553871683623, 2490.7509249075038, 2501.1371076978503, 2440.3289095498703, 2443.816713746467, 2444.3033735385507, 2447.730749617541, 2452.681151450318, 2081.55246621977, 2355.845635638525, 2382.826903720239, 2406.3743426709007, 2421.2010036687852, 11459.541618335257, 11668.241564298294, 11675.94162029193, 11689.0, 11692.532298708042, 5857.85355366115, 6004.279486461609, 6012.819615411545, 6027.367126451703, 6043.425874541911, 3517.208628058692, 3599.4420864766003, 3608.0979755061235, 3665.6334366563397, 3670.0457880950876, 1249.9812502812465, 1252.5803855708348, 1295.0481396173805, 1300.7333496633169, 1318.815365848782, 16080.919595402065, 16104.275307611186, 16120.435784747595, 16120.59698507535, 16151.434699785452, 4823.758812059383, 4876.984776153413, 4936.432310284292, 4952.678075925053, 4990.0, 13431.597052088453, 13543.932280338535, 13555.796663050061, 13557.796633050511, 13581.11722738019, 5083.796648134107, 5952.434518720715, 6028.397160284024, 6045.969770151121, 6059.818205453844, 5002.649814513022, 5015.22264049073, 5290.444503327095, 5290.81482148123, 5377.8117765878005, 3459.6713312235297, 4028.0586574440736, 4100.0774825664585, 4130.545639979599, 4214.3678448232395, 1114.420501339299, 1116.0736588631578, 1121.5850135449937, 1123.7583919457193, 1130.8982191602631, 948.8813898262717, 965.8937516873139, 981.877265341818, 990.782027953849, 997.8453339732353, 6354.745810167541, 6428.646424446701, 6625.900611490832, 6704.564203326866, 6734.821406253984, 905.0, 906.977325566866, 1007.5566750629699, 1007.8337074382645, 1024.2215915903967, 4017.0760725033406, 4074.9388759168637, 4137.110521237948, 4183.163367326486, 4200.264953633102, 13765.449382024806, 14227.78658320126, 14242.572722818331, 14275.92862035704, 14300.284985750672, 13171.60485185446, 14087.295635218197, 14244.857551424353, 14272.928635356964, 14383.352749126314, 10327.431991240554, 10491.580336786443, 10597.41714205726, 10601.84097238542, 10602.628907988183, 6757.979002493508, 6905.413039891465, 7046.541974771623, 7046.894296585453, 7050.753223637148, 12619.87380126187, 12821.294828784134, 12858.971282297582, 12952.611421657366, 12981.156224845356, 11980.820287695517, 13129.409176587267, 13197.472101115847, 13334.26661533625, 13355.53255636048, 9360.95319523398, 9696.127348538665, 9766.169875560658, 9800.509974501389, 9839.754006149904, 10486.423246721504, 10612.310199836986, 10631.20265980039, 10722.410267435365, 10743.838842417526, 9067.773305667411, 9145.314101442551, 9204.539773011456, 9336.0, 9363.297752668714, 8909.55452227399, 8935.642574296955, 8950.418222815497, 8980.775480612776, 9165.679201227926, 8053.919460805317, 8059.1134975153855, 8108.594570271581, 8137.877931831145, 8177.79555511117, 5912.497437717842, 5919.112133179885, 5922.703864806656, 6064.575479716554, 6126.203593532812, 4974.0, 5043.596512279082, 5921.822345329647, 5946.048632218824, 6024.367441418588], "value": 5296.226102371803, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.58959580875717, 401.5482582095143, 404.60955178253, 405.16738103197923, 409.26128338349275, 4688.578027977485, 4967.552920237178, 4994.775235114419, 5212.817551385702, 5213.765380557875, 4136.174039013915, 4184.539700632928, 5071.568916642081, 5153.665011774241, 5230.529252367286, 3812.332841752696, 3882.708796840227, 4457.662701189646, 4479.574440428154, 4617.168909596271, 4011.899702507446, 4224.429701990222, 4515.954840451586, 4602.286645569942, 4643.791029403685, 3652.945205821915, 3672.026912868092, 4511.864644060667, 4522.2312206924735, 4557.908841823161, 4498.752568608726, 4537.682362234645, 4541.72749635023, 4564.338170965191, 4585.592563903944, 3761.9090463765747, 3836.3094642964256, 4276.5723427656985, 4297.634701050414, 4358.760268185249, 4518.502964673916, 4829.8024398898415, 5027.245913113025, 5075.842766824085, 5174.379074511083, 4704.129735998817, 4721.551452611996, 5111.284420181178, 5300.727825321895, 5306.575473962073, 5407.61819180273, 5613.663180209201, 5652.954203472371, 5681.545476361881, 5696.202531645573, 4148.751074935483, 4819.614430845524, 4836.0, 4836.129496690594, 4896.971635956454, 5019.874503137415, 5599.444352835938, 5735.8566035849435, 5768.673494878841, 5865.187628041765, 3966.960330396659, 4788.760561971888, 4977.701337919736, 4977.950220497821, 5006.424261209934, 4544.431946006749, 4897.853064408074, 5089.949100509021, 5115.411727651293, 5120.462351453044, 4497.6956682561695, 5043.3191519145175, 5092.236164575269, 5199.7140157291715, 5256.842294731164, 4763.73799441034, 5587.972060139673, 5638.490871562116, 5656.462636049569, 5672.0, 1531.5175719648275, 1552.472159465787, 1639.3606493467557, 1696.2875592251285, 1749.615084681368, 1314.9151949641584, 1340.4906135668384, 1390.5133203378737, 1397.3851505337618, 1408.4718230663502, 4866.8783280417565, 5124.385073791169, 5289.259503669489, 5310.176922577007, 5368.248445217672, 5167.534921857051, 5187.481251874782, 5367.436419176009, 5745.913811292834, 5830.41695830422, 4413.668974826836, 4784.244368170969, 4845.878853028632, 5190.273361729361, 5238.476152384807, 2712.538868392378, 2790.8696977724053, 2795.3151477888046, 2917.957429799149, 3033.9393212135633, 5150.742462876841, 5458.235846981426, 5665.960254308446, 5739.077409068009, 5972.492338151305, 4885.656444477726, 5655.830325090254, 5724.484796368347, 5896.646201227854, 6014.51883849291, 6099.69501524922, 6120.969395153084, 6129.2032035835055, 6149.569530132937, 6184.474319682788, 4318.762468064161, 5294.258803767552, 5474.644148130359, 5685.8443563822475, 5694.0, 4593.678442508993, 4909.7545122743, 5516.724163791875, 5710.628809127394, 5837.912431313619, 5376.946230537645, 5831.801103205685, 6005.699715014144, 6017.488513476228, 6026.578139530367, 4113.938290925698, 4985.252212168204, 4998.650094493496, 5020.949790502048, 5058.974705126451, 5399.514043736082, 5649.802256920988, 5709.802510594081, 5796.913046304225, 5822.7000334868335, 4546.4544254689645, 4958.925616115834, 5023.3125277976105, 5072.554322018207, 5558.694271815089, 4852.204684266844, 5568.860778480571, 5601.60788744784, 5627.971860140673, 5637.49262566371, 4431.0, 4494.865154045384, 5478.589105817, 5577.0, 5642.520385767256, 5660.29246344207, 5693.829185124453, 5704.828855134353, 5752.511036561939, 5770.682612456355, 4713.9292910607055, 4910.0, 5266.8419947401635, 5500.654482958418, 5556.249906262603], "value": 4360.49019363706, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.529091962983, 768.6886810841611, 793.5516433215233, 879.8460269452846, 896.7713233125552, 16009.359625614976, 17448.91275543622, 17817.821821781778, 18034.729479057823, 18083.095845207736, 15847.683046339067, 16317.592060198502, 16334.34662613497, 16381.42665006725, 16424.425145119923, 974.3277138774258, 1001.8547310639952, 1002.9097381235688, 1003.0471052500118, 1004.8593196952432, 973.2894986659743, 1002.3634991780235, 1007.5516395204137, 1016.425719468498, 1027.7687520307945, 1057.751428414322, 1064.159314141827, 1067.6423398161626, 1069.3690722473739, 1097.6706987903597, 1047.5181416548396, 1057.973550661232, 1060.931039482431, 1061.4427425601543, 1062.6493257225136, 682.7371461987121, 784.2432053068759, 786.3944762532875, 789.4237206838982, 791.3076058448858, 15267.236638168048, 16063.992000999875, 17314.91342543292, 18289.451316460396, 18505.98217098059, 14641.121532708072, 14703.852961470355, 14730.410783568752, 14944.62638434037, 15128.0, 11882.702932426671, 12182.878171218263, 12192.451339689735, 12223.205491643015, 12393.93803030988, 3860.9227815443815, 3907.179492306619, 3926.8429262829454, 3962.8612998544913, 4103.733257338294, 118216.99999999914, 120552.0, 122901.99999999911, 123070.38464807619, 124558.37720811428, 11315.603953861575, 11569.819336887176, 11655.242409243372, 11759.706007349885, 12134.393280335948, 23971.520569588858, 24131.517369652858, 24355.391115221908, 24601.876990614932, 24718.752812472005, 8183.6726530938095, 8205.097439281673, 8215.34277257818, 8224.095349511548, 8294.543800090933, 4016.5581786003518, 4535.727856328631, 4579.22153233951, 4599.632029437637, 4852.320675105488, 3666.211764470652, 3708.35103856824, 3724.1434470071495, 3731.3470142724914, 3746.38184699527, 641.425923798198, 653.833272515515, 666.1074160624786, 668.0346898731384, 668.759246671198, 672.4452326830286, 703.2124021096405, 708.7129712466459, 726.0379996504653, 745.1244787374812, 9326.813463730687, 10256.487175641338, 10312.845307320396, 10476.685699428877, 10631.7773456052, 649.331188875459, 682.9516691877943, 719.1370355573333, 719.8164468060611, 735.8381156145639, 5135.948640513547, 5177.064343230162, 5193.246979187997, 5303.390110137307, 5321.680699158062, 84110.57944710237, 105554.0, 106142.46928765306, 106692.9330706683, 107071.46464267629, 62235.44411389661, 71338.0, 95730.9999999986, 96452.0, 97020.0, 84949.0, 85695.14304856872, 86132.56933715292, 86506.56746716225, 88039.0, 10014.59941602357, 10184.338018028804, 10204.0, 10251.12865406419, 10282.485875706036, 52499.68750781261, 52829.0, 53506.66233344198, 53765.73117134389, 53903.730481347346, 64715.029274561864, 64763.0, 65791.0, 65949.67025164844, 66193.0, 9148.542572871196, 11652.242604230698, 11694.122940779305, 11823.527058917547, 12284.508619655113, 14555.708885822436, 15622.984506007073, 15792.92103539475, 16006.279717412977, 16127.354905803637, 14466.132032078109, 14546.927265363605, 14621.342039608457, 14636.341364638403, 14721.92639036798, 14276.785848212492, 14302.427902883768, 14690.41238350454, 14886.181260030802, 15205.08769473791, 13807.86192138106, 14538.854611454173, 14730.337134829173, 14753.409863605335, 14883.47907823221, 10677.89322106769, 10686.786264274826, 10818.67543973682, 11155.051820595016, 11404.657860264206, 11342.319460832376, 11661.125415593695, 12123.969007748063, 12148.21036632616, 12220.511179553074], "value": 8045.408348922251, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1551.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 120.0, 118.0, 121.0, 100.0, 174.0, 174.0, 124.0, 176.0, 174.0], "value": 138.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 110.0, 140.0, 110.0, 110.0, 111.0, 137.0, 129.0, 110.0], "value": 120.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 204.0, 232.0, 208.0, 218.0, 200.0, 196.0, 195.0, 194.0, 212.0], "value": 206.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 83.0, 85.0, 101.0, 63.0, 59.0, 66.0, 86.0, 64.0, 88.0], "value": 76.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 84.0, 70.0, 70.0, 70.0, 81.0, 69.0, 70.0, 82.0], "value": 75.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 136.0, 118.0, 88.0, 99.0, 88.0, 89.0, 88.0, 88.0], "value": 103.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 143.0, 95.0, 102.0, 152.0, 152.0, 95.0, 95.0, 105.0, 127.0], "value": 119.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 100.0, 91.0, 141.0, 135.0, 90.0, 88.0, 92.0, 120.0, 81.0], "value": 102.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 66.0, 67.0, 69.0, 68.0, 69.0, 68.0, 68.0, 67.0], "value": 67.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 57.0, 71.0, 67.0, 65.0, 60.0, 59.0, 62.0, 61.0], "value": 62.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 76.0, 86.0, 101.0, 101.0, 99.0, 100.0, 82.0, 86.0, 81.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 133.0, 167.0, 147.0, 137.0, 143.0, 151.0, 131.0, 189.0, 143.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 169.0, 159.0, 202.0, 166.0, 163.0, 170.0, 164.0, 163.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 73.0, 84.0, 84.0, 62.0, 61.0, 81.0, 84.0, 81.0, 75.0], "value": 74.5, "unit": "score"}]}]} 14:19:14 INFO - Return code: 0 14:19:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:19:14 ERROR - # TBPL SUCCESS # 14:19:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:19:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:19:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:19:14 INFO - 2017-05-23 14:19:14.149 screenresolution[1656:10580] starting screenresolution argv=screenresolution get 14:19:14 INFO - 2017-05-23 14:19:14.152 screenresolution[1656:10580] Display 0: 1600x1200x32@60 14:19:14 INFO - 2017-05-23 14:19:14.158 screenresolution[1657:10583] starting screenresolution argv=screenresolution list 14:19:14 INFO - Available Modes on Display 0 14:19:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:19:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:19:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:19:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:19:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:19:14 INFO - Intel Iris: 14:19:14 INFO - Chipset Model: Intel Iris 14:19:14 INFO - Type: GPU 14:19:14 INFO - Bus: Built-In 14:19:14 INFO - VRAM (Dynamic, Max): 1536 MB 14:19:14 INFO - Vendor: Intel (0x8086) 14:19:14 INFO - Device ID: 0x0a2e 14:19:14 INFO - Revision ID: 0x0009 14:19:14 INFO - Displays: 14:19:14 INFO - Display: 14:19:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:19:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:19:14 INFO - Display Serial Number: 1600x1200 60 14:19:14 INFO - Main Display: Yes 14:19:14 INFO - Mirror: Off 14:19:14 INFO - Online: Yes 14:19:14 INFO - Rotation: Supported 14:19:14 INFO - Return code: 0 14:19:14 INFO - Running post-action listener: _resource_record_post_action 14:19:14 INFO - [mozharness: 2017-05-23 21:19:14.395288Z] Finished run-tests step (success) 14:19:14 INFO - Running post-run listener: _resource_record_post_run 14:19:14 INFO - Total resource usage - Wall time: 1191s; CPU: 54.0%; Read bytes: 38434816; Write bytes: 422733824; Read time: 256; Write time: 1421 14:19:14 INFO - TinderboxPrint: CPU usage
54.4% 14:19:14 INFO - TinderboxPrint: I/O read bytes / time
38,434,816 / 256 14:19:14 INFO - TinderboxPrint: I/O write bytes / time
422,733,824 / 1,421 14:19:14 INFO - TinderboxPrint: CPU idle
2,173.7 (45.6%) 14:19:14 INFO - TinderboxPrint: CPU system
953.5 (20.0%) 14:19:14 INFO - TinderboxPrint: CPU user
1,638.6 (34.4%) 14:19:14 INFO - TinderboxPrint: Swap in / out
160,481,280 / 0 14:19:14 INFO - install - Wall time: 15s; CPU: 53.0%; Read bytes: 121934336; Write bytes: 114245632; Read time: 10896; Write time: 244 14:19:14 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36124672; Write bytes: 298391552; Read time: 233; Write time: 1145 14:19:14 INFO - Running post-run listener: _upload_blobber_files 14:19:14 INFO - Blob upload gear active. 14:19:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:19:14 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 14:19:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:19:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:19:15 INFO - (blobuploader) - INFO - Open directory for files ... 14:19:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:19:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:19:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:19:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:19:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:19:16 INFO - (blobuploader) - INFO - Done attempting. 14:19:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:19:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:19:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:19:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:19:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:19:17 INFO - (blobuploader) - INFO - Done attempting. 14:19:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:19:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:19:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:19:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:19:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:19:18 INFO - (blobuploader) - INFO - Done attempting. 14:19:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:19:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:19:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:19:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:19:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:19:19 INFO - (blobuploader) - INFO - Done attempting. 14:19:19 INFO - (blobuploader) - INFO - Iteration through files over. 14:19:19 INFO - Return code: 0 14:19:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:19:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:19:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bff53c2b14251ac8e0556bf07ef3f4b8ba1b886cf2ff8d86cae051a5968109c883169fef063a6177e658935fcaad85b5a22e81325051d80c844198ad146a153b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8616b67b6d86b95f8de1641d7ff97c3b21174fadcec29c3c6990e231f4c8bf2487dfd21679023d06fe5fb4ed81b64c60a4c0dce9934bb30b365a522ba649c072", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6fbb0476b044c0256e64c008a1c867600d8578364e82b2dff5545683d9b8664adaff422f048eeb47f6a2f1c78b733c27a758ee9a853d02295d16cc8611554e34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20353c659d8dfef27d2a28207db383449fe426bc9f8b90c74cf57b94eed2ffc49f58bc0014b801da98698594b59b0f80e6978abf7641491cac2224f71945140a"} 14:19:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:19:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:19:19 INFO - Contents: 14:19:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bff53c2b14251ac8e0556bf07ef3f4b8ba1b886cf2ff8d86cae051a5968109c883169fef063a6177e658935fcaad85b5a22e81325051d80c844198ad146a153b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8616b67b6d86b95f8de1641d7ff97c3b21174fadcec29c3c6990e231f4c8bf2487dfd21679023d06fe5fb4ed81b64c60a4c0dce9934bb30b365a522ba649c072", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6fbb0476b044c0256e64c008a1c867600d8578364e82b2dff5545683d9b8664adaff422f048eeb47f6a2f1c78b733c27a758ee9a853d02295d16cc8611554e34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20353c659d8dfef27d2a28207db383449fe426bc9f8b90c74cf57b94eed2ffc49f58bc0014b801da98698594b59b0f80e6978abf7641491cac2224f71945140a"} 14:19:19 INFO - Running post-run listener: copy_logs_to_upload_dir 14:19:19 INFO - Copying logs to upload dir... 14:19:19 INFO - mkdir: /builds/slave/test/build/upload/logs 14:19:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.660682 ========= master_lag: 2.64 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-23 14:19:22.632703) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 14:19:22.641464) ========= 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.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bff53c2b14251ac8e0556bf07ef3f4b8ba1b886cf2ff8d86cae051a5968109c883169fef063a6177e658935fcaad85b5a22e81325051d80c844198ad146a153b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8616b67b6d86b95f8de1641d7ff97c3b21174fadcec29c3c6990e231f4c8bf2487dfd21679023d06fe5fb4ed81b64c60a4c0dce9934bb30b365a522ba649c072", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6fbb0476b044c0256e64c008a1c867600d8578364e82b2dff5545683d9b8664adaff422f048eeb47f6a2f1c78b733c27a758ee9a853d02295d16cc8611554e34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20353c659d8dfef27d2a28207db383449fe426bc9f8b90c74cf57b94eed2ffc49f58bc0014b801da98698594b59b0f80e6978abf7641491cac2224f71945140a"} build_url:https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011227 build_url: 'https://queue.taskcluster.net/v1/task/bo6DLI-iQ8e2aa2F3kFEYQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bff53c2b14251ac8e0556bf07ef3f4b8ba1b886cf2ff8d86cae051a5968109c883169fef063a6177e658935fcaad85b5a22e81325051d80c844198ad146a153b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8616b67b6d86b95f8de1641d7ff97c3b21174fadcec29c3c6990e231f4c8bf2487dfd21679023d06fe5fb4ed81b64c60a4c0dce9934bb30b365a522ba649c072", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6fbb0476b044c0256e64c008a1c867600d8578364e82b2dff5545683d9b8664adaff422f048eeb47f6a2f1c78b733c27a758ee9a853d02295d16cc8611554e34", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20353c659d8dfef27d2a28207db383449fe426bc9f8b90c74cf57b94eed2ffc49f58bc0014b801da98698594b59b0f80e6978abf7641491cac2224f71945140a"}' ========= master_lag: 0.67 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 14:19:23.322972) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 14:19:23.328507) ========= 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.sEvtZtLGrO/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.tbg6LBO8Rb/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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.005229 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 14:19:23.360930) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 14:19:23.361286) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 14:19:23.361606) ========= ========= Total master_lag: 3.41 =========