builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0033 starttime: 1490199793.92 results: success (0) buildid: 20170322075035 builduid: 4c53a0471de34579a732a1d7e7dedab8 revision: 00c595de162736689a83e894a84f9eb99a5fa6ac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 09:23:13.924675) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 09:23:13.925125) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 09:23:13.925396) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 09:23:13.950112) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:23:13.950478) ========= 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.iZbRGZz2r9/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.mFsCBM0sup/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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-03-22 09:23:13-- 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% 7.98M=0.001s 2017-03-22 09:23:14 (7.98 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103022 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:23:14.077086) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:23:14.077396) ========= 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.iZbRGZz2r9/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.mFsCBM0sup/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.020823 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:23:14.125685) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:23:14.126043) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 00c595de162736689a83e894a84f9eb99a5fa6ac --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 00c595de162736689a83e894a84f9eb99a5fa6ac --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iZbRGZz2r9/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.mFsCBM0sup/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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-03-22 09:23:14,174 truncating revision to first 12 chars 2017-03-22 09:23:14,175 Setting DEBUG logging. 2017-03-22 09:23:14,175 attempt 1/10 2017-03-22 09:23:14,175 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/00c595de1627?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-22 09:23:14,583 unpacking tar archive at: mozilla-central-00c595de1627/testing/mozharness/ program finished with exit code 0 elapsedTime=0.667212 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:23:14.816982) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 09:23:14.817284) ========= script_repo_revision: 00c595de162736689a83e894a84f9eb99a5fa6ac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 09:23:14.817788) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 09:23:14.818051) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 09:23:14.838298) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-22 09:23:14.838691) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iZbRGZz2r9/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.mFsCBM0sup/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 09:23:15 INFO - MultiFileLogger online at 20170322 09:23:15 in /builds/slave/test 09:23:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:23:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:23:15 INFO - {'append_to_log': False, 09:23:15 INFO - 'base_work_dir': '/builds/slave/test', 09:23:15 INFO - 'blob_upload_branch': 'Firefox', 09:23:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:23:15 INFO - 'branch': 'Firefox', 09:23:15 INFO - 'buildbot_json_path': 'buildprops.json', 09:23:15 INFO - 'config_files': ('talos/mac_config.py',), 09:23:15 INFO - 'default_actions': ('clobber', 09:23:15 INFO - 'read-buildbot-config', 09:23:15 INFO - 'download-and-extract', 09:23:15 INFO - 'populate-webroot', 09:23:15 INFO - 'create-virtualenv', 09:23:15 INFO - 'install', 09:23:15 INFO - 'run-tests'), 09:23:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:23:15 INFO - 'download_minidump_stackwalk': True, 09:23:15 INFO - 'download_symbols': 'ondemand', 09:23:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:23:15 INFO - 'tooltool.py': '/tools/tooltool.py', 09:23:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:23:15 INFO - '/tools/misc-python/virtualenv.py')}, 09:23:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:23:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:23:15 INFO - 'gecko_profile': False, 09:23:15 INFO - 'gecko_profile_interval': 0, 09:23:15 INFO - 'installer_path': 'installer.exe', 09:23:15 INFO - 'log_level': 'info', 09:23:15 INFO - 'log_name': 'talos', 09:23:15 INFO - 'log_to_console': True, 09:23:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:23:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:23:15 INFO - 'opt_config_files': (), 09:23:15 INFO - 'pip_index': False, 09:23:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:23:15 INFO - 'cmd': ('bash', 09:23:15 INFO - '-c', 09:23:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:23:15 INFO - 'enabled': True, 09:23:15 INFO - 'halt_on_failure': False, 09:23:15 INFO - 'name': 'check_screen_resolution'},), 09:23:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:23:15 INFO - 'cmd': ('bash', 09:23:15 INFO - '-c', 09:23:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:23:15 INFO - 'enabled': True, 09:23:15 INFO - 'halt_on_failure': False, 09:23:15 INFO - 'name': 'check_screen_resolution'},), 09:23:15 INFO - 'run_cmd_checks_enabled': True, 09:23:15 INFO - 'suite': 'dromaeojs-e10s', 09:23:15 INFO - 'system_bits': '32', 09:23:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:23:15 INFO - 'title': 't-yosemite-r7-0033', 09:23:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:23:15 INFO - 'use_talos_json': True, 09:23:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:23:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:23:15 INFO - 'work_dir': 'build'} 09:23:15 INFO - [mozharness: 2017-03-22 16:23:15.014955Z] Running clobber step. 09:23:15 INFO - Running pre-action listener: _resource_record_pre_action 09:23:15 INFO - Running main action method: clobber 09:23:15 INFO - rmtree: /builds/slave/test/build 09:23:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:23:17 INFO - Running post-action listener: _resource_record_post_action 09:23:17 INFO - [mozharness: 2017-03-22 16:23:17.018790Z] Finished clobber step (success) 09:23:17 INFO - [mozharness: 2017-03-22 16:23:17.018960Z] Running read-buildbot-config step. 09:23:17 INFO - Running pre-action listener: _resource_record_pre_action 09:23:17 INFO - Running main action method: read_buildbot_config 09:23:17 INFO - Using buildbot properties: 09:23:17 INFO - { 09:23:17 INFO - "project": "", 09:23:17 INFO - "product": "firefox", 09:23:17 INFO - "script_repo_revision": "production", 09:23:17 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:23:17 INFO - "repository": "", 09:23:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:23:17 INFO - "buildid": "20170322075035", 09:23:17 INFO - "pgo_build": "False", 09:23:17 INFO - "basedir": "/builds/slave/test", 09:23:17 INFO - "buildnumber": 132, 09:23:17 INFO - "slavename": "t-yosemite-r7-0033", 09:23:17 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:23:17 INFO - "platform": "yosemite_r7", 09:23:17 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:23:17 INFO - "branch": "mozilla-central", 09:23:17 INFO - "repo_path": "mozilla-central", 09:23:17 INFO - "revision": "00c595de162736689a83e894a84f9eb99a5fa6ac", 09:23:17 INFO - "stage_platform": "macosx64", 09:23:17 INFO - "builduid": "4c53a0471de34579a732a1d7e7dedab8", 09:23:17 INFO - "slavebuilddir": "test" 09:23:17 INFO - } 09:23:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:23:17 INFO - Running post-action listener: _resource_record_post_action 09:23:17 INFO - [mozharness: 2017-03-22 16:23:17.022312Z] Finished read-buildbot-config step (success) 09:23:17 INFO - [mozharness: 2017-03-22 16:23:17.022445Z] Running download-and-extract step. 09:23:17 INFO - Running pre-action listener: _resource_record_pre_action 09:23:17 INFO - Running main action method: download_and_extract 09:23:17 INFO - mkdir: /builds/slave/test/build/tests 09:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:23:17 INFO - trying https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:23:17 INFO - Downloading https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:23:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:23:18 INFO - Downloaded 1325 bytes. 09:23:18 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:23:18 INFO - Using the following test package requirements: 09:23:18 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:18 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:23:18 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:23:18 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:18 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:23:18 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:18 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:23:18 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:18 INFO - u'jsshell-mac.zip'], 09:23:18 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:18 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:23:18 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:23:18 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:18 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:23:18 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:18 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:23:18 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:18 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:23:18 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:23:18 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:23:18 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:23:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:23:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:23:18 INFO - Fetch https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:23:20 INFO - Expected file size: 52400421 09:23:20 INFO - Obtained file size: 52400421 09:23:36 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:23:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:23:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:23:36 INFO - Fetch https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:23:38 INFO - Expected file size: 52400421 09:23:38 INFO - Obtained file size: 52400421 09:23:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:23:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:23:53 INFO - Fetch https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:23:54 INFO - Expected file size: 14404171 09:23:54 INFO - Obtained file size: 14404171 09:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:23:54 INFO - trying https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:23:54 INFO - Downloading https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:23:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:23:57 INFO - Downloaded 75372597 bytes. 09:23:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:23:57 INFO - mkdir: /builds/slave/test/properties 09:23:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:23:57 INFO - Writing to file /builds/slave/test/properties/build_url 09:23:57 INFO - Contents: 09:23:57 INFO - build_url:https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:23:58 INFO - Running post-action listener: _resource_record_post_action 09:23:58 INFO - Running post-action listener: set_extra_try_arguments 09:23:58 INFO - [mozharness: 2017-03-22 16:23:58.051268Z] Finished download-and-extract step (success) 09:23:58 INFO - [mozharness: 2017-03-22 16:23:58.051414Z] Running populate-webroot step. 09:23:58 INFO - Running pre-action listener: _resource_record_pre_action 09:23:58 INFO - Running main action method: populate_webroot 09:23:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:23:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:23:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:23:58 INFO - '--tppagecycles', 09:23:58 INFO - '7'], 09:23:58 INFO - 'tests': ['tsvgm']}}, 09:23:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:23:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:23:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:23:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:23:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:23:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:23:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:23:58 INFO - 'talos_options': ['--disable-e10s'], 09:23:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:23:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:23:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:23:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:23:58 INFO - 'talos_options': ['--disable-e10s'], 09:23:58 INFO - 'tests': ['damp', 'tps']}, 09:23:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:23:58 INFO - 'tests': ['damp', 'tps']}, 09:23:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:23:58 INFO - 'tests': ['dromaeo_dom']}, 09:23:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:23:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:23:58 INFO - 'tests': ['basic_compositor_video']}, 09:23:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:23:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:23:58 INFO - 'tests': ['a11yr', 09:23:58 INFO - 'ts_paint', 09:23:58 INFO - 'tpaint', 09:23:58 INFO - 'sessionrestore', 09:23:58 INFO - 'sessionrestore_no_auto_restore', 09:23:58 INFO - 'tabpaint']}, 09:23:58 INFO - 'other-e10s': {'tests': ['a11yr', 09:23:58 INFO - 'ts_paint', 09:23:58 INFO - 'tpaint', 09:23:58 INFO - 'sessionrestore', 09:23:58 INFO - 'sessionrestore_no_auto_restore', 09:23:58 INFO - 'tabpaint']}, 09:23:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:23:58 INFO - 'ts_paint', 09:23:58 INFO - 'tpaint', 09:23:58 INFO - 'sessionrestore', 09:23:58 INFO - 'sessionrestore_no_auto_restore', 09:23:58 INFO - 'tabpaint']}, 09:23:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:23:58 INFO - 'ts_paint', 09:23:58 INFO - 'tpaint', 09:23:58 INFO - 'sessionrestore', 09:23:58 INFO - 'sessionrestore_no_auto_restore', 09:23:58 INFO - 'tabpaint']}, 09:23:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:23:58 INFO - 'tests': ['a11yr', 09:23:58 INFO - 'ts_paint', 09:23:58 INFO - 'tpaint', 09:23:58 INFO - 'sessionrestore', 09:23:58 INFO - 'sessionrestore_no_auto_restore', 09:23:58 INFO - 'tabpaint']}, 09:23:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:23:58 INFO - 'tests': ['a11yr', 09:23:58 INFO - 'ts_paint', 09:23:58 INFO - 'tpaint', 09:23:58 INFO - 'sessionrestore', 09:23:58 INFO - 'sessionrestore_no_auto_restore', 09:23:58 INFO - 'tabpaint']}, 09:23:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:23:58 INFO - 'tests': ['tsvgx', 09:23:58 INFO - 'tsvgr_opacity', 09:23:58 INFO - 'tart', 09:23:58 INFO - 'tscrollx', 09:23:58 INFO - 'cart', 09:23:58 INFO - 'tsvg_static']}, 09:23:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:23:58 INFO - 'tsvgr_opacity', 09:23:58 INFO - 'tart', 09:23:58 INFO - 'tscrollx', 09:23:58 INFO - 'cart', 09:23:58 INFO - 'tsvg_static']}, 09:23:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:23:58 INFO - 'talos_options': ['--disable-e10s'], 09:23:58 INFO - 'tests': ['tp5o']}, 09:23:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:23:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:23:58 INFO - 'talos_options': ['--disable-e10s', 09:23:58 INFO - '--xperf_path', 09:23:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:23:58 INFO - 'tests': ['tp5n']}, 09:23:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:23:58 INFO - 'talos_options': ['--xperf_path', 09:23:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:23:58 INFO - 'tests': ['tp5n']}}, 09:23:58 INFO - 'talos.zip': {'path': '', 09:23:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:23:58 INFO - Running post-action listener: _resource_record_post_action 09:23:58 INFO - [mozharness: 2017-03-22 16:23:58.069787Z] Finished populate-webroot step (success) 09:23:58 INFO - [mozharness: 2017-03-22 16:23:58.069899Z] Running create-virtualenv step. 09:23:58 INFO - Running pre-action listener: _resource_record_pre_action 09:23:58 INFO - Running main action method: create_virtualenv 09:23:58 INFO - Creating virtualenv /builds/slave/test/build/venv 09:23:58 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 09:23:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:23:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:23:58 INFO - Using real prefix '/tools/python27' 09:23:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:23:58 INFO - Installing distribute.............................................................................................................................................................................................done. 09:24:01 INFO - Installing pip.................done. 09:24:01 INFO - Return code: 0 09:24:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:24:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:24:02 INFO - Reading from file tmpfile_stdout 09:24:02 INFO - Output received: 09:24:02 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) 09:24:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:24:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:02 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f86ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f86d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacb4b8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f648d30>, '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 0x10f56fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f63dbe8>, '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.iZbRGZz2r9/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.mFsCBM0sup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 09:24:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:24:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZbRGZz2r9/Render', 09:24:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:02 INFO - 'HOME': '/Users/cltbld', 09:24:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:02 INFO - 'LOGNAME': 'cltbld', 09:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:02 INFO - 'MOZ_NO_REMOTE': '1', 09:24:02 INFO - 'NO_EM_RESTART': '1', 09:24:02 INFO - 'PAGER': '/bin/cat', 09:24:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:02 INFO - 'PWD': '/builds/slave/test', 09:24:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:02 INFO - 'SHELL': '/bin/bash', 09:24:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mFsCBM0sup/Listeners', 09:24:02 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 09:24:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:02 INFO - 'USER': 'cltbld', 09:24:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:02 INFO - 'XPC_FLAGS': '0x0', 09:24:02 INFO - 'XPC_SERVICE_NAME': '0', 09:24:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:02 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 09:24:02 INFO - Cleaning up... 09:24:02 INFO - Return code: 0 09:24:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:24:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:02 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f86ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f86d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacb4b8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f648d30>, '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 0x10f56fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f63dbe8>, '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.iZbRGZz2r9/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.mFsCBM0sup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 09:24:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:24:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZbRGZz2r9/Render', 09:24:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:02 INFO - 'HOME': '/Users/cltbld', 09:24:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:02 INFO - 'LOGNAME': 'cltbld', 09:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:02 INFO - 'MOZ_NO_REMOTE': '1', 09:24:02 INFO - 'NO_EM_RESTART': '1', 09:24:02 INFO - 'PAGER': '/bin/cat', 09:24:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:02 INFO - 'PWD': '/builds/slave/test', 09:24:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:02 INFO - 'SHELL': '/bin/bash', 09:24:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mFsCBM0sup/Listeners', 09:24:02 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 09:24:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:02 INFO - 'USER': 'cltbld', 09:24:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:02 INFO - 'XPC_FLAGS': '0x0', 09:24:02 INFO - 'XPC_SERVICE_NAME': '0', 09:24:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:02 INFO - Downloading/unpacking psutil>=3.1.1 09:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:24:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:24:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:24:05 INFO - Installing collected packages: psutil 09:24:05 INFO - Running setup.py install for psutil 09:24:06 INFO - building 'psutil._psutil_osx' extension 09:24:06 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 09:24:06 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 09:24:06 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 09:24:06 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 09:24:06 INFO - building 'psutil._psutil_posix' extension 09:24:06 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 09:24:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:24:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:24:06 INFO - ^ 09:24:06 INFO - 1 warning generated. 09:24:06 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 09:24:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:24:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:24:06 INFO - Successfully installed psutil 09:24:06 INFO - Cleaning up... 09:24:06 INFO - Return code: 0 09:24:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:24:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f86ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f86d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacb4b8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f648d30>, '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 0x10f56fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f63dbe8>, '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.iZbRGZz2r9/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.mFsCBM0sup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 09:24:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:24:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:24:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZbRGZz2r9/Render', 09:24:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:06 INFO - 'HOME': '/Users/cltbld', 09:24:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:06 INFO - 'LOGNAME': 'cltbld', 09:24:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:06 INFO - 'MOZ_NO_REMOTE': '1', 09:24:06 INFO - 'NO_EM_RESTART': '1', 09:24:06 INFO - 'PAGER': '/bin/cat', 09:24:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:06 INFO - 'PWD': '/builds/slave/test', 09:24:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:06 INFO - 'SHELL': '/bin/bash', 09:24:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mFsCBM0sup/Listeners', 09:24:06 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 09:24:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:06 INFO - 'USER': 'cltbld', 09:24:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:06 INFO - 'XPC_FLAGS': '0x0', 09:24:06 INFO - 'XPC_SERVICE_NAME': '0', 09:24:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:24:07 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 09:24:07 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 09:24:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:07 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 09:24:07 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 09:24:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:24:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:24:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:24:09 INFO - Installing collected packages: mozsystemmonitor 09:24:09 INFO - Running setup.py install for mozsystemmonitor 09:24:09 INFO - Successfully installed mozsystemmonitor 09:24:09 INFO - Cleaning up... 09:24:09 INFO - Return code: 0 09:24:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:24:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f86ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f86d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacb4b8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f648d30>, '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 0x10f56fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f63dbe8>, '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.iZbRGZz2r9/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.mFsCBM0sup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 09:24:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:24:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:24:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZbRGZz2r9/Render', 09:24:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:11 INFO - 'HOME': '/Users/cltbld', 09:24:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:11 INFO - 'LOGNAME': 'cltbld', 09:24:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:11 INFO - 'MOZ_NO_REMOTE': '1', 09:24:11 INFO - 'NO_EM_RESTART': '1', 09:24:11 INFO - 'PAGER': '/bin/cat', 09:24:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:11 INFO - 'PWD': '/builds/slave/test', 09:24:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:11 INFO - 'SHELL': '/bin/bash', 09:24:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mFsCBM0sup/Listeners', 09:24:11 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 09:24:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:11 INFO - 'USER': 'cltbld', 09:24:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:11 INFO - 'XPC_FLAGS': '0x0', 09:24:11 INFO - 'XPC_SERVICE_NAME': '0', 09:24:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:11 INFO - Downloading/unpacking jsonschema==2.5.1 09:24:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:24:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:24:14 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 09:24:14 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 09:24:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:14 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 09:24:14 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 09:24:14 INFO - Downloading functools32-3.2.3-2.tar.gz 09:24:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:24:15 INFO - warning: no files found matching '*.txt' 09:24:15 INFO - no previously-included directories found matching 'build' 09:24:15 INFO - no previously-included directories found matching 'dist' 09:24:15 INFO - no previously-included directories found matching '.git*' 09:24:15 INFO - Installing collected packages: jsonschema, functools32 09:24:15 INFO - Running setup.py install for functools32 09:24:15 INFO - warning: no files found matching '*.txt' 09:24:15 INFO - no previously-included directories found matching 'build' 09:24:15 INFO - no previously-included directories found matching 'dist' 09:24:15 INFO - no previously-included directories found matching '.git*' 09:24:15 INFO - Successfully installed jsonschema functools32 09:24:15 INFO - Cleaning up... 09:24:15 INFO - Return code: 0 09:24:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f86ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f86d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacb4b8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f648d30>, '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 0x10f56fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f63dbe8>, '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.iZbRGZz2r9/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.mFsCBM0sup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 09:24:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:24:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:24:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZbRGZz2r9/Render', 09:24:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:15 INFO - 'HOME': '/Users/cltbld', 09:24:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:15 INFO - 'LOGNAME': 'cltbld', 09:24:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:15 INFO - 'MOZ_NO_REMOTE': '1', 09:24:15 INFO - 'NO_EM_RESTART': '1', 09:24:15 INFO - 'PAGER': '/bin/cat', 09:24:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:15 INFO - 'PWD': '/builds/slave/test', 09:24:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:15 INFO - 'SHELL': '/bin/bash', 09:24:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mFsCBM0sup/Listeners', 09:24:15 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 09:24:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:15 INFO - 'USER': 'cltbld', 09:24:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:15 INFO - 'XPC_FLAGS': '0x0', 09:24:15 INFO - 'XPC_SERVICE_NAME': '0', 09:24:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:24:15 INFO - Cleaning up... 09:24:15 INFO - Return code: 0 09:24:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f86ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f86d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacb4b8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f648d30>, '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 0x10f56fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f63dbe8>, '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.iZbRGZz2r9/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.mFsCBM0sup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 09:24:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:24:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:24:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZbRGZz2r9/Render', 09:24:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:15 INFO - 'HOME': '/Users/cltbld', 09:24:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:15 INFO - 'LOGNAME': 'cltbld', 09:24:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:15 INFO - 'MOZ_NO_REMOTE': '1', 09:24:15 INFO - 'NO_EM_RESTART': '1', 09:24:15 INFO - 'PAGER': '/bin/cat', 09:24:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:15 INFO - 'PWD': '/builds/slave/test', 09:24:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:15 INFO - 'SHELL': '/bin/bash', 09:24:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mFsCBM0sup/Listeners', 09:24:15 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 09:24:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:15 INFO - 'USER': 'cltbld', 09:24:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:15 INFO - 'XPC_FLAGS': '0x0', 09:24:15 INFO - 'XPC_SERVICE_NAME': '0', 09:24:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:15 INFO - Downloading/unpacking blobuploader==1.2.4 09:24:15 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 09:24:15 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 09:24:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:15 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 09:24:15 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 09:24:18 INFO - Downloading blobuploader-1.2.4.tar.gz 09:24:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:24:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:24:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:24: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 09:24: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 09:24:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24: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 09:24: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 09:24:19 INFO - Downloading docopt-0.6.1.tar.gz 09:24:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:24:19 INFO - Installing collected packages: blobuploader, requests, docopt 09:24:19 INFO - Running setup.py install for blobuploader 09:24:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:24:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:24:20 INFO - Running setup.py install for requests 09:24:20 INFO - Running setup.py install for docopt 09:24:20 INFO - Successfully installed blobuploader requests docopt 09:24:20 INFO - Cleaning up... 09:24:20 INFO - Return code: 0 09:24:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:20 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f86ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f86d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacb4b8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f648d30>, '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 0x10f56fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f63dbe8>, '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.iZbRGZz2r9/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.mFsCBM0sup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 09:24:20 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:24:20 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:24:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZbRGZz2r9/Render', 09:24:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:20 INFO - 'HOME': '/Users/cltbld', 09:24:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:20 INFO - 'LOGNAME': 'cltbld', 09:24:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:20 INFO - 'MOZ_NO_REMOTE': '1', 09:24:20 INFO - 'NO_EM_RESTART': '1', 09:24:20 INFO - 'PAGER': '/bin/cat', 09:24:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:20 INFO - 'PWD': '/builds/slave/test', 09:24:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:20 INFO - 'SHELL': '/bin/bash', 09:24:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mFsCBM0sup/Listeners', 09:24:20 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 09:24:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:20 INFO - 'USER': 'cltbld', 09:24:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:20 INFO - 'XPC_FLAGS': '0x0', 09:24:20 INFO - 'XPC_SERVICE_NAME': '0', 09:24:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:21 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-__ZV2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:21 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-DEMqPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:21 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-6enQaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:21 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-YQjDmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:21 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-8MOyvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:21 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-lBQmDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:21 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-05UIGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:21 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-968vjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:21 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-3y9cay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:21 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-cv3BvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:22 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-6Y8Z2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:22 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-fM3cGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:22 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-cC76fC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:22 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-76HZ8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:22 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-ziKuYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:22 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-CnHIn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:22 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-JiErXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:24:22 INFO - Running setup.py install for manifestparser 09:24:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:24:22 INFO - Running setup.py install for mozcrash 09:24:23 INFO - Running setup.py install for mozdebug 09:24:23 INFO - Running setup.py install for mozdevice 09:24:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:24:23 INFO - Running setup.py install for mozfile 09:24:23 INFO - Running setup.py install for mozhttpd 09:24:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:24:23 INFO - Running setup.py install for mozinfo 09:24:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:24:23 INFO - Running setup.py install for mozInstall 09:24:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:24:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:24:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:24:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:24:23 INFO - Running setup.py install for mozleak 09:24:24 INFO - Running setup.py install for mozlog 09:24:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:24:24 INFO - Running setup.py install for moznetwork 09:24:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:24:24 INFO - Running setup.py install for mozprocess 09:24:24 INFO - Running setup.py install for mozprofile 09:24:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:24:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:24:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:24:24 INFO - Running setup.py install for mozrunner 09:24:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:24:24 INFO - Running setup.py install for mozscreenshot 09:24:25 INFO - Running setup.py install for moztest 09:24:25 INFO - Running setup.py install for mozversion 09:24:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:24:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:24:25 INFO - Cleaning up... 09:24:25 INFO - Return code: 0 09:24:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:25 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f86ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f86d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacb4b8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f648d30>, '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 0x10f56fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f63dbe8>, '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.iZbRGZz2r9/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.mFsCBM0sup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 09:24:25 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:24:25 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:24:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZbRGZz2r9/Render', 09:24:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:25 INFO - 'HOME': '/Users/cltbld', 09:24:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:25 INFO - 'LOGNAME': 'cltbld', 09:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:25 INFO - 'MOZ_NO_REMOTE': '1', 09:24:25 INFO - 'NO_EM_RESTART': '1', 09:24:25 INFO - 'PAGER': '/bin/cat', 09:24:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:25 INFO - 'PWD': '/builds/slave/test', 09:24:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:25 INFO - 'SHELL': '/bin/bash', 09:24:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mFsCBM0sup/Listeners', 09:24:25 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 09:24:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:25 INFO - 'USER': 'cltbld', 09:24:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:25 INFO - 'XPC_FLAGS': '0x0', 09:24:25 INFO - 'XPC_SERVICE_NAME': '0', 09:24:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:25 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-SGAxBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:25 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)) 09:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:25 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-RICyKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:25 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)) 09:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:25 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-6cKvWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:25 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)) 09:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:25 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-gmGXL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:26 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)) 09:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-AJhSfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:26 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)) 09:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-1H3ZeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:26 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)) 09:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-gxzDLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:26 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)) 09:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-s1sb8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:26 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)) 09:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-rwgt_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:26 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)) 09:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-WAliBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:26 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)) 09:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-Wot7Dz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:26 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)) 09:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-UDc48S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:26 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)) 09:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:26 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-JmIxiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:27 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)) 09:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:27 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-GW3VG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:27 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)) 09:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:27 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-rYckrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:27 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)) 09:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:27 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-xDhQOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:27 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)) 09:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:27 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-lq3KPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:27 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)) 09:24:27 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)) 09:24:27 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)) 09:24:27 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)) 09:24:27 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)) 09:24:27 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)) 09:24:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:24:27 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 09:24:27 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 09:24:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:27 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 09:24:27 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 09:24:30 INFO - Downloading blessings-1.6.tar.gz 09:24:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:24:30 INFO - Installing collected packages: blessings 09:24:30 INFO - Running setup.py install for blessings 09:24:30 INFO - Successfully installed blessings 09:24:30 INFO - Cleaning up... 09:24:30 INFO - Return code: 0 09:24:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:24:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:24:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:24:30 INFO - Reading from file tmpfile_stdout 09:24:30 INFO - Current package versions: 09:24:30 INFO - blessings == 1.6 09:24:30 INFO - blobuploader == 1.2.4 09:24:30 INFO - docopt == 0.6.1 09:24:30 INFO - functools32 == 3.2.3-2 09:24:30 INFO - jsonschema == 2.5.1 09:24:30 INFO - manifestparser == 1.1 09:24:30 INFO - mozInstall == 1.12 09:24:30 INFO - mozcrash == 1.0 09:24:30 INFO - mozdebug == 0.1 09:24:30 INFO - mozdevice == 0.49 09:24:30 INFO - mozfile == 1.2 09:24:30 INFO - mozhttpd == 0.7 09:24:30 INFO - mozinfo == 0.9 09:24:30 INFO - mozleak == 0.1 09:24:30 INFO - mozlog == 3.4 09:24:30 INFO - moznetwork == 0.27 09:24:30 INFO - mozprocess == 0.25 09:24:30 INFO - mozprofile == 0.28 09:24:30 INFO - mozrunner == 6.13 09:24:30 INFO - mozscreenshot == 0.1 09:24:30 INFO - mozsystemmonitor == 0.3 09:24:30 INFO - moztest == 0.8 09:24:30 INFO - mozversion == 1.4 09:24:30 INFO - psutil == 3.1.1 09:24:30 INFO - requests == 1.2.3 09:24:30 INFO - wsgiref == 0.1.2 09:24:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:30 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f86ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f86d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacb4b8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f648d30>, '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 0x10f56fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f63dbe8>, '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.iZbRGZz2r9/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.mFsCBM0sup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 09:24:30 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:24:30 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:24:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZbRGZz2r9/Render', 09:24:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:30 INFO - 'HOME': '/Users/cltbld', 09:24:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:30 INFO - 'LOGNAME': 'cltbld', 09:24:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:30 INFO - 'MOZ_NO_REMOTE': '1', 09:24:30 INFO - 'NO_EM_RESTART': '1', 09:24:30 INFO - 'PAGER': '/bin/cat', 09:24:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:30 INFO - 'PWD': '/builds/slave/test', 09:24:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:30 INFO - 'SHELL': '/bin/bash', 09:24:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mFsCBM0sup/Listeners', 09:24:30 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 09:24:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:30 INFO - 'USER': 'cltbld', 09:24:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:30 INFO - 'XPC_FLAGS': '0x0', 09:24:30 INFO - 'XPC_SERVICE_NAME': '0', 09:24:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:30 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)) 09:24:30 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)) 09:24:30 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)) 09:24:30 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)) 09:24:30 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)) 09:24:30 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)) 09:24:30 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)) 09:24:30 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)) 09:24:30 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)) 09:24:30 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)) 09:24:30 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)) 09:24:30 INFO - Cleaning up... 09:24:30 INFO - Return code: 0 09:24:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:30 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10f86ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f86d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacb4b8da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f648d30>, '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 0x10f56fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f63dbe8>, '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.iZbRGZz2r9/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.mFsCBM0sup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 09:24:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:24:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:24:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZbRGZz2r9/Render', 09:24:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:30 INFO - 'HOME': '/Users/cltbld', 09:24:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:30 INFO - 'LOGNAME': 'cltbld', 09:24:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:30 INFO - 'MOZ_NO_REMOTE': '1', 09:24:30 INFO - 'NO_EM_RESTART': '1', 09:24:30 INFO - 'PAGER': '/bin/cat', 09:24:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:30 INFO - 'PWD': '/builds/slave/test', 09:24:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:30 INFO - 'SHELL': '/bin/bash', 09:24:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mFsCBM0sup/Listeners', 09:24:30 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 09:24:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:30 INFO - 'USER': 'cltbld', 09:24:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:30 INFO - 'XPC_FLAGS': '0x0', 09:24:30 INFO - 'XPC_SERVICE_NAME': '0', 09:24:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:24:31 INFO - Cleaning up... 09:24:31 INFO - Return code: 0 09:24:31 INFO - Running post-action listener: _resource_record_post_action 09:24:31 INFO - Running post-action listener: _start_resource_monitoring 09:24:31 INFO - Starting resource monitoring. 09:24:31 INFO - [mozharness: 2017-03-22 16:24:31.262914Z] Finished create-virtualenv step (success) 09:24:31 INFO - [mozharness: 2017-03-22 16:24:31.263886Z] Running install step. 09:24:31 INFO - Running pre-action listener: _resource_record_pre_action 09:24:31 INFO - Running main action method: install 09:24:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:24:31 INFO - Reading from file tmpfile_stdout 09:24:31 INFO - Detecting whether we're running mozinstall >=1.0... 09:24:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:24:31 INFO - Reading from file tmpfile_stdout 09:24:31 INFO - Output received: 09:24:31 INFO - Usage: mozinstall [options] installer 09:24:31 INFO - Options: 09:24:31 INFO - -h, --help show this help message and exit 09:24:31 INFO - -d DEST, --destination=DEST 09:24:31 INFO - Directory to install application into. [default: 09:24:31 INFO - "/builds/slave/test"] 09:24:31 INFO - --app=APP Application being installed. [default: firefox] 09:24:31 INFO - mkdir: /builds/slave/test/build/application 09:24:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:24:52 INFO - Reading from file tmpfile_stdout 09:24:52 INFO - Output received: 09:24:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:24:52 INFO - Running post-action listener: _resource_record_post_action 09:24:52 INFO - [mozharness: 2017-03-22 16:24:52.329971Z] Finished install step (success) 09:24:52 INFO - [mozharness: 2017-03-22 16:24:52.330155Z] Running run-tests step. 09:24:52 INFO - Running pre-action listener: _resource_record_pre_action 09:24:52 INFO - Running main action method: run_tests 09:24:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:24:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:24:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:24:52 INFO - 2017-03-22 09:24:52.345 screenresolution[1912:12610] starting screenresolution argv=screenresolution get 09:24:52 INFO - 2017-03-22 09:24:52.347 screenresolution[1912:12610] Display 0: 1600x1200x32@60 09:24:52 INFO - 2017-03-22 09:24:52.353 screenresolution[1913:12613] starting screenresolution argv=screenresolution list 09:24:52 INFO - Available Modes on Display 0 09:24:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:24:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:24:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:24:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:24:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:24:52 INFO - Intel Iris: 09:24:52 INFO - Chipset Model: Intel Iris 09:24:52 INFO - Type: GPU 09:24:52 INFO - Bus: Built-In 09:24:52 INFO - VRAM (Dynamic, Max): 1536 MB 09:24:52 INFO - Vendor: Intel (0x8086) 09:24:52 INFO - Device ID: 0x0a2e 09:24:52 INFO - Revision ID: 0x0009 09:24:52 INFO - Displays: 09:24:52 INFO - Display: 09:24:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:24:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:24:52 INFO - Display Serial Number: 1600x1200 60 09:24:52 INFO - Main Display: Yes 09:24:52 INFO - Mirror: Off 09:24:52 INFO - Online: Yes 09:24:52 INFO - Rotation: Supported 09:24:52 INFO - Return code: 0 09:24:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:24:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:24:52 INFO - Python 2.7.3 09:24:52 INFO - Return code: 0 09:24:52 INFO - grabbing minidump binary from tooltool 09:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 09:24:52 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 0x10f648d30>, '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 0x10f56fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f63dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:24:52 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 09:24:52 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 09:24:52 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 09:24:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:24:52 INFO - Return code: 0 09:24:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:24:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:24:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:24:52 INFO - ENV: RUST_BACKTRACE is now 1 09:24:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:24:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:24:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 09:24:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0033 --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 09:24:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZbRGZz2r9/Render', 09:24:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:52 INFO - 'HOME': '/Users/cltbld', 09:24:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:52 INFO - 'LOGNAME': 'cltbld', 09:24:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:24:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:52 INFO - 'MOZ_NO_REMOTE': '1', 09:24:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:24:52 INFO - 'NO_EM_RESTART': '1', 09:24:52 INFO - 'PAGER': '/bin/cat', 09:24:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:52 INFO - 'PWD': '/builds/slave/test', 09:24:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:24:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:52 INFO - 'RUST_BACKTRACE': '1', 09:24:52 INFO - 'SHELL': '/bin/bash', 09:24:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mFsCBM0sup/Listeners', 09:24:52 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 09:24:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:52 INFO - 'USER': 'cltbld', 09:24:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:52 INFO - 'XPC_FLAGS': '0x0', 09:24:52 INFO - 'XPC_SERVICE_NAME': '0', 09:24:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--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 09:24:52 INFO - mozversion application_buildid: 20170322075035 09:24:52 INFO - mozversion application_changeset: 00c595de162736689a83e894a84f9eb99a5fa6ac 09:24:52 INFO - mozversion application_display_name: Nightly 09:24:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:24:52 INFO - mozversion application_name: Firefox 09:24:52 INFO - mozversion application_remotingname: firefox 09:24:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:24:52 INFO - mozversion application_vendor: Mozilla 09:24:52 INFO - mozversion application_version: 55.0a1 09:24:52 INFO - mozversion platform_buildid: 20170322075035 09:24:52 INFO - mozversion platform_changeset: 00c595de162736689a83e894a84f9eb99a5fa6ac 09:24:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:24:52 INFO - mozversion platform_version: 55.0a1 09:24:52 INFO - using testdate: 1490199892 09:24:52 INFO - actual date: 1490199892 09:24:52 INFO - starting webserver on 'localhost:49497' 09:24:52 INFO - SUITE-START | Running 2 tests 09:24:52 INFO - TEST-START | dromaeo_css 09:24:52 INFO - operating with platform_type : mac_ 09:24:52 INFO - Initialising browser for dromaeo_css test... 09:24:52 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpsqpaNZ/profile http://localhost:49497/getInfo.html) 09:24:55 INFO - PID 1923 | 2017-03-22 09:24:55.779 plugin-container[1925:12781] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 09:24:55 INFO - PID 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:24:55 INFO - PID 1923 | 2017-03-22 09:24:55.783 plugin-container[1925:12781] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x921b, name = 'com.apple.CFPasteboardClient' 09:24:55 INFO - PID 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:24:55 INFO - PID 1923 | 2017-03-22 09:24:55.783 plugin-container[1925:12781] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:24:55 INFO - PID 1923 | __metrics Screen width/height:1600/1200 09:24:55 INFO - PID 1923 | colorDepth:24 09:24:55 INFO - PID 1923 | Browser inner width/height: 1024/689 09:24:55 INFO - PID 1923 | __metrics 09:24:55 ERROR - PID 1923 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:24:56 INFO - TEST-INFO | 1923: exit 0 09:24:56 INFO - Browser initialized. 09:24:56 INFO - Running cycle 1/1 for dromaeo_css test... 09:24:56 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpsqpaNZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:24:59 INFO - PID 1926 | 2017-03-22 09:24:59.250 plugin-container[1927:12909] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 09:24:59 INFO - PID 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:24:59 INFO - PID 1926 | 2017-03-22 09:24:59.251 plugin-container[1927:12909] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 09:24:59 INFO - PID 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:24:59 INFO - PID 1926 | 2017-03-22 09:24:59.251 plugin-container[1927:12909] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:25:09 INFO - PID 1926 | RSS: Main: 228560896 09:25:09 INFO - PID 1926 | 09:28:12 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:49497/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49497/tests/dromaeo/cssquery-ext.html) 09:28:14 INFO - PID 1926 | RSS: Main: 250830848 09:28:14 INFO - PID 1926 | 09:29:57 INFO - PID 1926 | ************************* 09:29:57 INFO - PID 1926 | A coding exception was thrown and uncaught in a Task. 09:29:57 INFO - PID 1926 | 09:29:57 ERROR - PID 1926 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:29:57 INFO - PID 1926 | Full stack: 09:29:57 INFO - PID 1926 | ************************* 09:31:17 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:49497/tests/dromaeo/cssquery-ext.html (next: http://localhost:49497/tests/dromaeo/cssquery-jquery.html) 09:31:17 INFO - PID 1926 | RSS: Main: 257961984 09:31:17 INFO - PID 1926 | 09:34:19 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:49497/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49497/tests/dromaeo/cssquery-mootools.html) 09:34:20 INFO - PID 1926 | RSS: Main: 271138816 09:34:20 INFO - PID 1926 | 09:37:22 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:49497/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49497/tests/dromaeo/cssquery-prototype.html) 09:37:22 INFO - PID 1926 | RSS: Main: 264437760 09:37:22 INFO - PID 1926 | 09:40:25 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:49497/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49497/tests/dromaeo/cssquery-yui.html) 09:40:26 INFO - PID 1926 | RSS: Main: 274681856 09:40:26 INFO - PID 1926 | 09:43:29 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:49497/tests/dromaeo/cssquery-yui.html (next: http://localhost:49497/tests/dromaeo/cssquery-dojo.html) 09:43:29 INFO - PID 1926 | RSS: Main: 268398592 09:43:29 INFO - PID 1926 | 09:43:29 INFO - PID 1926 | __start_tp_report 09:43:29 INFO - PID 1926 | _x_x_mozilla_page_load 09:43:29 INFO - PID 1926 | _x_x_mozilla_page_load_details 09:43:29 INFO - PID 1926 | |i|pagename|runs| 09:43:29 INFO - PID 1926 | |0;dojo.html;2839.730225628565;3541.344851202526;3586.2827434513106;3587.0494319005484;3636.709063274938;6121.938780612192;6144.293406258282;6171.352008039158;6199.597026193294;6255.249370075586;5979.192808970788;6094.847628809282;6108.541859360555;6134.008014331981;6172.197614310145;4557.339185818071;5443.156310771837;5580.86047848803;5633.577481688869;5650.915236271459;4760.523947605247;5624.0596994037;5639.2668953036045;5668.149777533383;5832.300123985127;5520.972395137998;5687.914681279784;5703.5152012079025;5741.167530708045;5761;5444.40111587725;5609.214709940612;5627.6060675752715;5670.489655930986;5703.3441154267375;5560.582956278296;5572.749226284827;5588.329400471969;5608.186812912146;5614.1859430382365;5990.97004514979;6244.906326405107;6265.87468250637;6272.1219029335925;6282.968585157045;6423.807285781434;6501.609903405763;6629.436497897685;6702.09521714572;6749.291324410915;6748.460123190133;6770.796876093725;6771.085903403026;6773.254941956381;6829.214640316378;6320.905186422207;6322.209723784527;6326.588771729823;6348.841278968062;6354.142190804275;5506.256655351556;5587.413321601254;6716.798496045127;6765.52641315113;6850.691718872663;5157.510036546522;6347.619142851444;6428;6595;6600.471962243009;6509.707063182168;6670.299618540081;6757.3242675732035;6768.69540870662;6776.627285499247;5673.744681489343;6667.899981500282;6674.365935236145;6679.298673639295;6697.095892054608;6174.104754810527;6331.145295385064;6374.617522948638;6699.229588597374;6720.798376048726;4756.1438940990265;5281.313429254173;5298.496642818926;5303.655262407931;5322.600804939645;4696.966667333181;4708.670393072452;4719.126961512131;4726.621870250372;4762.595179409789;6220.253569571681;6285.497160227171;6329.145565348711;6342;6361.109444677749;6714.764983225564;6778.356056174655;6799;6808.829779255559;6819.965900170567;6333.1766870306565;6350.872982540323;6366.395192456709;6402.231732192074;6418.390252925965;4445.1998640245065;5568.136938774496;5653.491185793216;6002.639841609517;6014.127951447016;5790.594658373873;6856.6228857413325;6881.277465866212;6926.376626103473;6961.095057642474;5669.85825354353;6668.899966500409;6815.556988795713;6818.28408017151;6828.146481689789;5714.914276285942;6935.445164386938;6940.09778728762;6954.443644508326;6995.580265184105;5448;6445.16212892321;6482.611043337415;6515.478761698958;6643.900341494785;6721;6727.966360168168;6737.696803643946;6742.494312926452;6753.932460675331;5570.275864137636;6373.74505019794;6546.083548303146;6551.508636852351;6557.442617377576;6345.428911397997;6454.709538070704;6519.3480651936325;6559.245686746085;6576.80269591913;6245.28179259391;6398.936010639834;6444;6456.192975878015;6469;6476.384743449365;6577.309382514958;6605.306442823627;6607.702653380706;6640.468762498892;6226.501879849692;6288.717007734572;6354.968225159029;6399.488040956619;6439.227292724903 09:43:29 INFO - PID 1926 | |1;ext.html;701.2286484866647;707.8690442268179;738.47937204271;1271.1610337177458;1271.6566527037705;37041;38044;38174;38197.472101115956;38231.808840955826;36773;37080.81459592705;38176.809115954384;38273.425898611546;38324.425133623015;8021.077576078753;8218.917810821875;8395.95802020989;8636.654533818672;8645.092265312145;8033.6384862681025;8377.45546539476;8611.698590549331;8736.170063843956;8865.024847266795;7567.508111972704;8789.560521973908;8809.559522023905;8879.023307436177;8879.200871921526;8788.8681669775;8832.735017949472;8847.203751662317;8847.646094156225;8864.379493435463;8609.61256743448;8647.740567782977;8674.002489713637;8680;8683.305335573139;31239.84380078085;37878;37899.81050094732;38154.42768358477;38298.61701382978;2968.5695574141846;2979.865906034234;2980;2990.2225421390376;3011.4278287125376;27746.167614971582;27816.860915695495;27939.44121117566;28846.711532884612;28936.27659308513;2655.5352813257605;2661.718027045051;2704.445588654321;2708.133397312861;2765.6681198256338;315426;319937;320206.79793202237;320699;320956;49143.26285105726;49249.26126108111;49367.25949110766;49425.75287123613;49685.50314496881;51623.451296461164;51630.48369516257;51904.48095519072;52213.73893130586;52226.477735222164;14289.214093224973;14575;14620.780688289684;14654.754345880507;14687.26563671812;9791.804163916824;11553.884461155281;11649.359285239394;11883.465244064038;11895.643130706092;13318.200907945555;13657.317134143452;13704.90360771136;13803.585892423243;13889.02776805614;5926.644401335933;5937.168796368512;5966;5975.073863551156;6000.759969601254;847.6270441005938;854.4019186569426;971.5288085278635;1003.3027046202819;1027.5992362978445;22503;23057;24083.03667853301;24088.87955560246;24129.879350603485;989.3322007644811;1041.9426931518688;1122.9213955023233;1132.4620805117504;1138.4194061028934;9337.393069450463;10772.461376931122;12246.571370002008;12288.139830211978;12302.81545776814;314491;374308;375564;377697;378069;39724;40501;44530.33204501935;54072;55163.724181379635;1319.8746119118669;1508.3815635589356;1515.6514001779653;1523.7333466643292;1531.9429593580394;20547;26257.868710656323;26289.342766430993;26897.327566810985;28373;25991.870040649676;35021.949341519794;35955.741549045655;36386.45420318644;36562;321681;323477;324388;325696;326973;30906.69093309038;31433.52849707302;31786.682133178372;31794.84102579472;31809.363812724077;39023;40537.59462405338;40591.39112913368;41193.58806411897;41278;33915.98252052443;34106.82946585251;34566.65433345635;34783.4782478258;35262.94211173669;33921;33998.49002264919;34848.65151348454;35020.57181136928;35171.29657406786;34282.314353713286;34517.309653807286;34778.82610586931;34888.65111348854;35056.649433505336;27145.59281610817;28093;28752.85623571869;30368.544471832498;30613.23466913256;23041.308760737204;23260;23427.828608569846;23493.53012939766;23625.173118940755 09:43:29 INFO - PID 1926 | |2;jquery.html;424.54785653279254;427.19473791902254;435.6345143112746;436.93882856400097;457.81687325069987;19580.41258762236;20541.794582054175;21311.467213319676;21817.01823417946;21937.34187974363;21394.35816925491;22581.774182258174;22724.886375568098;23007.194748183818;23182.072717091298;5545.140503222007;6126.938730612681;6360.300366959631;6361.74553017881;6371;5114.744262786846;5922.555808314372;6327.303996560375;6332.271788744308;6333.651649159295;4720.693154944935;6279.466245369149;6287.308396076428;6302.905456418157;6311.968440157792;6178.691065446732;6276.152719382916;6281.59169653971;6291.968540157316;6294.339094395092;5321.122014867542;5509.201165830932;5554.72226388679;5562.443755624446;5618.381977982419;26831.06091286815;27267.727322726718;28513.144605661862;28561;28720.138395848157;1956.158851693771;1991.6315481635943;2008.839292856568;2019.8889061101634;2034.735484387035;15398.538043858702;16389.508314750576;16599.087050212354;16666.66666666676;16787.832121678748;1712.7944646642359;1714.6913555559995;1729.3860679458862;1729.5848996240939;1735.817739137385;130223;137537;143962;151111;151217;12513.061520386007;12756.936215318863;12983.805242921364;13045.412956416983;13302.201867887958;15979.360825567079;16222.432214872422;16249.350025998827;16251;16304.266308016167;8024.438289319692;8734.737957861274;8735.1701588349;9111.225545828678;9180.632774688938;7160.57036577807;8641.611127499278;8645.870311945326;8664;8850.300658299151;7866.7639970800965;7993.681042628017;8010.3992200584025;8020.79948001293;8052.516848989079;1957.8350881225647;2130.0521268035886;2227.3874684461903;2234.731832180149;2245.180509114194;856.8133135607229;933.5518950903604;941.18117238002;941.5762906692016;981.79873126009;15779.684406312039;20235.595288094155;20726.481837954172;20808.687869681966;20854.478638033866;843.5824266987757;939.8449255872841;957.7844984878486;961.6682244625594;971.844504879216;8402.655575107954;9952.552135153936;10798.352098874093;10804.351738895692;10839.512221950032;505774;582680;627023;628180;634946;119048;146634;150441.24779376033;150506;152297.23851380672;1177.1433078318566;1181.2085902445383;1205.6081773423673;1208.5769980506752;1209.5585111434264;12155.756884862076;17513.124343782503;17692.557686057953;17952.820471795636;18015.2793888243;42788;44650.77674611606;57416.71291643515;59287.70356148192;59561;75185.62407187928;75664.48671026439;77466.22533774815;77641;79004.60497697475;8639.222469977733;8666.913330866611;8674.956625216833;8735.650573977224;8831.116888311375;13599.79600306016;13622.182669039888;14019;14060.718785624435;14211.218382988622;9862.161716254199;11255.606053788406;11266.774664506827;11506.30962142274;11532;9656.95171524138;11036.006759391694;11161.386123763272;11248.156388270749;11502.94248528752;9164.908350916405;10181.083702466814;11630.53477860876;11689.590864319707;11874.346910919983;7276.854462910818;8582.270507006975;8845.934626917351;8911.866322005304;8987.370884038317;8540.444871083608;9878.901210988086;11702.88297117018;11857.288562686266;11860.76278474443 09:43:29 INFO - PID 1926 | |3;mootools.html;1354.1265883505139;1393.2128347483665;1406.4936622815792;1417.3834382043813;1438.654722866512;10327.328723632958;10582.36505809652;10727.570897164114;10753.83869241962;10803.675889723301;10452.581896724141;10720.571177152924;10720.678379648603;10724.624638137666;10774.97637724417;2424.624183251599;2442.4748679034005;2447.0089613706477;2453.0555736041665;2453.4970331082172;2187.752980800944;2393.569157551649;2432.805375569959;2441.5727247731666;2442.8167887408517;2383.2969274064194;2426.9271921842374;2438.3538362333998;2441.853488790678;2443.6701045358914;2053.784352642966;2352.3295860679623;2397.7722116398913;2404.4589967257225;2411.6141417373224;2350.9647355289685;2426.0538390027905;2435.561598912195;2440.6583078369044;2463.8398504097177;9221.492817895012;10842;10933.617323393722;10949.45252737368;10972.23194376386;5359.598030147755;5392.973035134838;6138.9693051534905;6391;6432.742690292383;3869.400242962345;4057.756534607933;4082.2447847148073;4123;4132.111596006873;1189.2329447506397;1500.7898894154828;1544.3822471011572;1565.506865337415;1566.1073188282683;13454.327283635877;13657.453701851913;14412;14571.70856582873;14590.562283131523;5052.115879721034;5085.237214417867;5366.7048312343195;5372.435894231127;5405.945940540544;11219.60731374398;11255.268407553647;11287.887121128684;11313.83029254562;11319.490622921823;6211.472024877846;6230.7819226327765;6236.844078898064;6241.906371404432;6252.437280644764;5147.819826306061;5476.972615136899;5491.780328786804;5758.971205143947;5847.6783776891825;4161.9583804161575;4195.202911446815;4257.318828987409;4280.6789490788315;4309.159713855831;1231.5689508671894;1260.4391045984632;1268.1123213750227;1278.778766278202;1281.5066199513255;598.9368870255331;663.8439966607843;708.4403321376158;727.0983979865035;757.6666266842572;5937.376575459514;6356.523260755462;6408.391202835724;6597.340265973365;6598.967005164944;564.5765675743193;584.4097461563852;597.2564157623699;598.3956204233755;598.4195330529379;3221.291315910496;3513.402721537345;3529.241213139136;3740.784245120347;3747.6252374762307;13411.932940335431;13706.931465342808;13768.380422880995;13895.235762033186;13920.721585568435;13493.122947008413;13502.189868607718;13658.658533536742;13704.10923289983;13739.793903091462;10071.295009349275;10153.593856245667;10184.694459166238;10203.438810865473;10285.382877027403;5904.232449781501;6470.676466176767;6643;6686.966565167143;6736.292689267556;11746.88253117492;12080.973117284777;12146.331951742386;12194.51221951112;12286.75426491483;11926.284422934652;12078.154529182873;12309.830566096207;12490.500379984698;12547.686307842378;8695.52174630376;8809.647614095364;8894.86657700148;8924.553772311228;8927.955360223417;9907.653232136841;10009.74975625586;10066.396016239049;10107.494625268855;10173.237007224636;7415.369693575891;7429.368503677248;8856.911430885866;8867.600957957036;8989.056149104512;8509.957450212709;8721.956390218009;8730.47617142948;8772.078931712334;8831.381803273709;7361.079865016873;7685.923140768745;7697.730579429693;7734.883976740466;7792.337651299703;5721.656700597978;5748.770049197985;5787.334456537552;5811.56413268998;5857.355690874086;5836.503897168788;5961.672108034099;5999.040153575407;6044.395560443921;6110.51115910735 09:43:29 INFO - PID 1926 | |4;prototype.html;334.2079272125064;390.2254025758866;399.7162014969372;406.87183537185786;410.6612045062823;5347.652402593829;6041.093835924608;6210.937890621092;6322.43098121169;6338.429541341278;5399.487048730374;6156.630602163873;6169.938300616993;6238.656873871935;6274.3098259191565;3802.9619703802887;4495.617872480843;4557.179707652621;4677.906441871178;4690.460597031334;4020.5778393268724;4674.626029917598;4680.625549955996;4681.438227412716;4681.648876334271;3611.2235869288097;4332.938430084634;4380.430544029272;4402.911941761163;4570.794314255866;3794.886153415388;4364.388985542027;4588.632909367243;4655.207745018159;4669.392978912737;3762.172322089136;3939.212157568498;3941.4628294964987;3954.9011274718073;3964.108075682977;4655.4180727409075;6060.181875446847;6076.605020673642;6088.269407671063;6104.26748790148;5337.893242135136;5418.91871621926;5441.830006548572;5481.917771233435;5487.396386397493;5552.222688823568;6276.592021518587;6678.933210667879;6685.699143538553;6767.864642707167;4433.933490997638;4437.001674623184;4447.17727220462;4447.533009034038;4455.933161002587;6603.14159159311;6782.321767823179;7027.754028608974;7107.502474826764;7137.750178743719;4298.946758044237;4388.956110438855;4517.10905020787;4524.298733696282;4547.613452856544;4011.5988401159652;4027.194561087794;4610.9308360374625;4633.768311584475;4639.86080417588;5214.817481388133;5387.380451248078;5442.265294185234;5458.77177635029;5487.42382049887;5282.392524859613;6120.969395152996;6244.875102497925;6785.541108661661;6790.898136527956;1239.6405042537745;1447.2835946206694;1565.1939251285596;1593.7370333894778;1639.9754003689716;1017.3031473440569;1017.7608262058559;1029.4543891737392;1038.2265212416646;1047.2930771729052;5205.55752761012;5792.420757924174;5964.85087872798;6450.967745161244;6459.12219879907;5958;6926.896096558556;6995.8456854619435;7003.439724822002;7054.435645148375;4916.8770780730765;6037.72830222641;6432.35676432353;6469.641375311237;6562.901556476561;3166.3825554017208;3593.3531964246686;3654.5016543217153;3678.3930651442747;3692.51997240357;5633.8591535211945;7073.434125269966;7183.46124040699;7191.820204494825;7199.9280007200305;6673.299303573055;6871.725130994804;7065.290722370693;7168.92831071693;7210.170830354471;5602.803901863415;7017.614031228331;7055.7177712891935;7181.96409017962;7194.136703595602;6663;6871.9656401717675;6969.616671083139;7017.333353331425;7087.149542054986;6022.915875142341;6520.185691370411;6846.863062738617;6878.449724021966;6883.621400822803;6807.591544507345;6946.305369463013;7096.542146646527;7235.565866048054;7238.457115716447;3606.837692303905;3769.754965927206;4192.9161416770885;4541.364209010675;4542.977285113553;5737.56968227393;6535.3464653534265;6780.643871225676;7057.0472986146215;7090.568394753279;5795.739191736467;6527.934720652733;6794.3545363190415;6815.318468153145;6831.4629208427705;4951.405831300123;6469.191351081115;6805.829854253683;6806.6256355900905;6830;5531.695756733257;6302.590331628429;6358.141650877073;6585.209774827052;6671.299513551007;5811.94188058114;5827.76688932438;5992.67040312787;6544.018397240452;6821.454283657196;6027.914975304312;6250.562460627712;6414.61512309263;6627.3372662733345;6632.303608121078 09:43:29 INFO - PID 1926 | |5;yui.html;631.6841579210395;638.773235501397;710.2081179484869;716.892401240084;730.4704089535086;14837.925810370947;15050;15543.067415955022;15632.14023228722;15638.921805390986;14919.850801491983;15014.62463438412;15041.398344066225;15171.772423413657;15206.619834504143;916.3814425262938;941.9293552983554;943.5470973932516;943.9056094390575;945.3382632157517;925.1719710858778;952.6903756279237;956.9473678947655;959.2565761534796;960.1262850805768;973.8587904753806;991.6430085169334;992.9950350248239;994.5176589354157;998.9700308990742;974.28390133252;982.8280050991084;986.7779749556364;987.5407935310116;994.9950250248738;727.5634619228473;728.4973368375856;737.181728281607;737.5058710663868;739.3604532079767;16128.59678508035;16165.272562734704;16534.503964881074;16809.32762689491;17120.315187392614;14538.636534086627;14727.568231819641;14775.482858100022;14922.104673719503;15351.07893526392;10408.84386734192;11396.544138234462;11613.767724645544;11646.883531164665;12301.031834906462;3008;3014.9246268843235;3015.25593743916;3057.770667199969;3157.636871759731;96622;113439.29841052309;114207;115295;119763;11197.384143872003;11297.830532542019;11393.772124557463;11409.25839820409;11464.31214127155;24352.634710479357;24573.87713061459;24828.00687972497;25017.124400646255;25031.37421564439;7601.961990190013;7804.453688241769;7927.682892684344;7931.920680793233;8102.230288122619;4110.815013324408;4190.455240818735;4196.664266858644;4206.810693518799;4220.873373798791;4001.4397984282223;4058.898527536835;4102.179564087135;4103.733257338264;4119.134981653826;835.415209353455;837.7738010737189;838.9580520973927;861.2851333393269;865.9826803464022;523.4529916237541;551.9613627046068;565.6069032022787;570.5150621971375;585.4701495146899;9565.378250413702;9604.711858644252;10184.694459166238;10295.691129266133;10398.116160126474;535.1945322289929;544.3331918399929;574.1502576187182;578.6788332475433;580.5152697497637;3737.850485980585;4511.458624965026;4734.07685501324;4784.976075119672;4794.184988551954;94324;98285.50857245667;101491;102052.48973755083;104737;57239.71380143073;59805;64595.677021615535;69391.65304173446;90985.54507227421;60935.69532152311;61867.381326186165;63381.683091584244;66877.66561167163;69796.6510167456;9056.592453339746;10600.4699765013;10683.412412317397;10767.730806729895;10812.297200682246;29932.25169370696;33597.16007099842;33610.83194584109;34217.65782342145;34883.65116348804;36271.730489432746;42943.57056429521;44601.55398445974;44949;45982.310265345375;12133.514659413524;12684.936575317064;12995.935020324838;13123.278219698008;13658.863411365759;13280.53518126861;14057.648558786112;14247.430102795772;14547.636309091935;14932.253387330808;13242.80135797983;13366.93316533411;13562.015561985783;13626.659333516742;13646.454141834216;10396;11746.882531174579;13010.21938683682;13170.60488185356;13426.798598020841;10405.479726013518;12719.109662323548;12752.936235318764;12822.935885320514;13305.334733263104;10309.896901030894;10947.945260273966;10968.23222374426;11110;11184.328940263611;7924;10441.791164176826;10903.509342079467;10921;11010.889891101308 09:43:29 INFO - PID 1926 | __end_tp_report 09:43:29 INFO - PID 1926 | __start_cc_report 09:43:29 INFO - PID 1926 | _x_x_mozilla_cycle_collect,420 09:43:29 INFO - PID 1926 | __end_cc_report 09:43:29 INFO - PID 1926 | __startTimestamp1490201009526__endTimestamp 09:43:29 INFO - PID 1926 | 09:43:29 INFO - PID 1926 | ------- Summary: start ------- 09:43:29 INFO - PID 1926 | Number of tests: 6 09:43:29 INFO - PID 1926 | 09:43:29 INFO - PID 1926 | [#0] dojo.html Cycles:165 Average:6109.00 Median:6345.43 stddev:749.09 (11.8%) stddev-sans-first:706.13 09:43:29 INFO - PID 1926 | Values: 2839.7 3541.3 3586.3 3587.0 3636.7 6121.9 6144.3 6171.4 6199.6 6255.2 5979.2 6094.8 6108.5 6134.0 6172.2 4557.3 5443.2 5580.9 5633.6 5650.9 4760.5 5624.1 5639.3 5668.1 5832.3 5521.0 5687.9 5703.5 5741.2 5761.0 5444.4 5609.2 5627.6 5670.5 5703.3 5560.6 5572.7 5588.3 5608.2 5614.2 5991.0 6244.9 6265.9 6272.1 6283.0 6423.8 6501.6 6629.4 6702.1 6749.3 6748.5 6770.8 6771.1 6773.3 6829.2 6320.9 6322.2 6326.6 6348.8 6354.1 5506.3 5587.4 6716.8 6765.5 6850.7 5157.5 6347.6 6428.0 6595.0 6600.5 6509.7 6670.3 6757.3 6768.7 6776.6 5673.7 6667.9 6674.4 6679.3 6697.1 6174.1 6331.1 6374.6 6699.2 6720.8 4756.1 5281.3 5298.5 5303.7 5322.6 4697.0 4708.7 4719.1 4726.6 4762.6 6220.3 6285.5 6329.1 6342.0 6361.1 6714.8 6778.4 6799.0 6808.8 6820.0 6333.2 6350.9 6366.4 6402.2 6418.4 4445.2 5568.1 5653.5 6002.6 6014.1 5790.6 6856.6 6881.3 6926.4 6961.1 5669.9 6668.9 6815.6 6818.3 6828.1 5714.9 6935.4 6940.1 6954.4 6995.6 5448.0 6445.2 6482.6 6515.5 6643.9 6721.0 6728.0 6737.7 6742.5 6753.9 5570.3 6373.7 6546.1 6551.5 6557.4 6345.4 6454.7 6519.3 6559.2 6576.8 6245.3 6398.9 6444.0 6456.2 6469.0 6476.4 6577.3 6605.3 6607.7 6640.5 6226.5 6288.7 6355.0 6399.5 6439.2 09:43:29 INFO - PID 1926 | 09:43:29 INFO - PID 1926 | [#1] ext.html Cycles:180 Average:47726.98 Median:34672.74 stddev:88741.48 (255.9%) stddev-sans-first:88920.18 09:43:29 INFO - PID 1926 | Values: 701.2 707.9 738.5 1271.2 1271.7 37041.0 38044.0 38174.0 38197.5 38231.8 36773.0 37080.8 38176.8 38273.4 38324.4 8021.1 8218.9 8396.0 8636.7 8645.1 8033.6 8377.5 8611.7 8736.2 8865.0 7567.5 8789.6 8809.6 8879.0 8879.2 8788.9 8832.7 8847.2 8847.6 8864.4 8609.6 8647.7 8674.0 8680.0 8683.3 31239.8 37878.0 37899.8 38154.4 38298.6 2968.6 2979.9 2980.0 2990.2 3011.4 27746.2 27816.9 27939.4 28846.7 28936.3 2655.5 2661.7 2704.4 2708.1 2765.7 315426.0 319937.0 320206.8 320699.0 320956.0 49143.3 49249.3 49367.3 49425.8 49685.5 51623.5 51630.5 51904.5 52213.7 52226.5 14289.2 14575.0 14620.8 14654.8 14687.3 9791.8 11553.9 11649.4 11883.5 11895.6 13318.2 13657.3 13704.9 13803.6 13889.0 5926.6 5937.2 5966.0 5975.1 6000.8 847.6 854.4 971.5 1003.3 1027.6 22503.0 23057.0 24083.0 24088.9 24129.9 989.3 1041.9 1122.9 1132.5 1138.4 9337.4 10772.5 12246.6 12288.1 12302.8 314491.0 374308.0 375564.0 377697.0 378069.0 39724.0 40501.0 44530.3 54072.0 55163.7 1319.9 1508.4 1515.7 1523.7 1531.9 20547.0 26257.9 26289.3 26897.3 28373.0 25991.9 35021.9 35955.7 36386.5 36562.0 321681.0 323477.0 324388.0 325696.0 326973.0 30906.7 31433.5 31786.7 31794.8 31809.4 39023.0 40537.6 40591.4 41193.6 41278.0 33916.0 34106.8 34566.7 34783.5 35262.9 33921.0 33998.5 34848.7 35020.6 35171.3 34282.3 34517.3 34778.8 34888.7 35056.6 27145.6 28093.0 28752.9 30368.5 30613.2 23041.3 23260.0 23427.8 23493.5 23625.2 09:43:29 INFO - PID 1926 | 09:43:29 INFO - PID 1926 | [#2] jquery.html Cycles:180 Average:36524.86 Median:28537.07 stddev:100796.11 (353.2%) stddev-sans-first:101042.42 09:43:29 INFO - PID 1926 | Values: 424.5 427.2 435.6 436.9 457.8 19580.4 20541.8 21311.5 21817.0 21937.3 21394.4 22581.8 22724.9 23007.2 23182.1 5545.1 6126.9 6360.3 6361.7 6371.0 5114.7 5922.6 6327.3 6332.3 6333.7 4720.7 6279.5 6287.3 6302.9 6312.0 6178.7 6276.2 6281.6 6292.0 6294.3 5321.1 5509.2 5554.7 5562.4 5618.4 26831.1 27267.7 28513.1 28561.0 28720.1 1956.2 1991.6 2008.8 2019.9 2034.7 15398.5 16389.5 16599.1 16666.7 16787.8 1712.8 1714.7 1729.4 1729.6 1735.8 130223.0 137537.0 143962.0 151111.0 151217.0 12513.1 12756.9 12983.8 13045.4 13302.2 15979.4 16222.4 16249.4 16251.0 16304.3 8024.4 8734.7 8735.2 9111.2 9180.6 7160.6 8641.6 8645.9 8664.0 8850.3 7866.8 7993.7 8010.4 8020.8 8052.5 1957.8 2130.1 2227.4 2234.7 2245.2 856.8 933.6 941.2 941.6 981.8 15779.7 20235.6 20726.5 20808.7 20854.5 843.6 939.8 957.8 961.7 971.8 8402.7 9952.6 10798.4 10804.4 10839.5 505774.0 582680.0 627023.0 628180.0 634946.0 119048.0 146634.0 150441.2 150506.0 152297.2 1177.1 1181.2 1205.6 1208.6 1209.6 12155.8 17513.1 17692.6 17952.8 18015.3 42788.0 44650.8 57416.7 59287.7 59561.0 75185.6 75664.5 77466.2 77641.0 79004.6 8639.2 8666.9 8675.0 8735.7 8831.1 13599.8 13622.2 14019.0 14060.7 14211.2 9862.2 11255.6 11266.8 11506.3 11532.0 9657.0 11036.0 11161.4 11248.2 11502.9 9164.9 10181.1 11630.5 11689.6 11874.3 7276.9 8582.3 8845.9 8911.9 8987.4 8540.4 9878.9 11702.9 11857.3 11860.8 09:43:29 INFO - PID 1926 | 09:43:29 INFO - PID 1926 | [#3] mootools.html Cycles:180 Average:6657.94 Median:2453.28 stddev:4084.66 (166.5%) stddev-sans-first:4076.68 09:43:29 INFO - PID 1926 | Values: 1354.1 1393.2 1406.5 1417.4 1438.7 10327.3 10582.4 10727.6 10753.8 10803.7 10452.6 10720.6 10720.7 10724.6 10775.0 2424.6 2442.5 2447.0 2453.1 2453.5 2187.8 2393.6 2432.8 2441.6 2442.8 2383.3 2426.9 2438.4 2441.9 2443.7 2053.8 2352.3 2397.8 2404.5 2411.6 2351.0 2426.1 2435.6 2440.7 2463.8 9221.5 10842.0 10933.6 10949.5 10972.2 5359.6 5393.0 6139.0 6391.0 6432.7 3869.4 4057.8 4082.2 4123.0 4132.1 1189.2 1500.8 1544.4 1565.5 1566.1 13454.3 13657.5 14412.0 14571.7 14590.6 5052.1 5085.2 5366.7 5372.4 5405.9 11219.6 11255.3 11287.9 11313.8 11319.5 6211.5 6230.8 6236.8 6241.9 6252.4 5147.8 5477.0 5491.8 5759.0 5847.7 4162.0 4195.2 4257.3 4280.7 4309.2 1231.6 1260.4 1268.1 1278.8 1281.5 598.9 663.8 708.4 727.1 757.7 5937.4 6356.5 6408.4 6597.3 6599.0 564.6 584.4 597.3 598.4 598.4 3221.3 3513.4 3529.2 3740.8 3747.6 13411.9 13706.9 13768.4 13895.2 13920.7 13493.1 13502.2 13658.7 13704.1 13739.8 10071.3 10153.6 10184.7 10203.4 10285.4 5904.2 6470.7 6643.0 6687.0 6736.3 11746.9 12081.0 12146.3 12194.5 12286.8 11926.3 12078.2 12309.8 12490.5 12547.7 8695.5 8809.6 8894.9 8924.6 8928.0 9907.7 10009.7 10066.4 10107.5 10173.2 7415.4 7429.4 8856.9 8867.6 8989.1 8510.0 8722.0 8730.5 8772.1 8831.4 7361.1 7685.9 7697.7 7734.9 7792.3 5721.7 5748.8 5787.3 5811.6 5857.4 5836.5 5961.7 5999.0 6044.4 6110.5 09:43:29 INFO - PID 1926 | 09:43:29 INFO - PID 1926 | [#4] prototype.html Cycles:180 Average:5342.73 Median:5892.88 stddev:1716.02 (29.1%) stddev-sans-first:1679.15 09:43:29 INFO - PID 1926 | Values: 334.2 390.2 399.7 406.9 410.7 5347.7 6041.1 6210.9 6322.4 6338.4 5399.5 6156.6 6169.9 6238.7 6274.3 3803.0 4495.6 4557.2 4677.9 4690.5 4020.6 4674.6 4680.6 4681.4 4681.6 3611.2 4332.9 4380.4 4402.9 4570.8 3794.9 4364.4 4588.6 4655.2 4669.4 3762.2 3939.2 3941.5 3954.9 3964.1 4655.4 6060.2 6076.6 6088.3 6104.3 5337.9 5418.9 5441.8 5481.9 5487.4 5552.2 6276.6 6678.9 6685.7 6767.9 4433.9 4437.0 4447.2 4447.5 4455.9 6603.1 6782.3 7027.8 7107.5 7137.8 4298.9 4389.0 4517.1 4524.3 4547.6 4011.6 4027.2 4610.9 4633.8 4639.9 5214.8 5387.4 5442.3 5458.8 5487.4 5282.4 6121.0 6244.9 6785.5 6790.9 1239.6 1447.3 1565.2 1593.7 1640.0 1017.3 1017.8 1029.5 1038.2 1047.3 5205.6 5792.4 5964.9 6451.0 6459.1 5958.0 6926.9 6995.8 7003.4 7054.4 4916.9 6037.7 6432.4 6469.6 6562.9 3166.4 3593.4 3654.5 3678.4 3692.5 5633.9 7073.4 7183.5 7191.8 7199.9 6673.3 6871.7 7065.3 7168.9 7210.2 5602.8 7017.6 7055.7 7182.0 7194.1 6663.0 6872.0 6969.6 7017.3 7087.1 6022.9 6520.2 6846.9 6878.4 6883.6 6807.6 6946.3 7096.5 7235.6 7238.5 3606.8 3769.8 4192.9 4541.4 4543.0 5737.6 6535.3 6780.6 7057.0 7090.6 5795.7 6527.9 6794.4 6815.3 6831.5 4951.4 6469.2 6805.8 6806.6 6830.0 5531.7 6302.6 6358.1 6585.2 6671.3 5811.9 5827.8 5992.7 6544.0 6821.5 6027.9 6250.6 6414.6 6627.3 6632.3 09:43:29 INFO - PID 1926 | 09:43:29 INFO - PID 1926 | [#5] yui.html Cycles:180 Average:18569.25 Median:33914.24 stddev:26656.80 (78.6%) stddev-sans-first:26697.55 09:43:29 INFO - PID 1926 | Values: 631.7 638.8 710.2 716.9 730.5 14837.9 15050.0 15543.1 15632.1 15638.9 14919.9 15014.6 15041.4 15171.8 15206.6 916.4 941.9 943.5 943.9 945.3 925.2 952.7 956.9 959.3 960.1 973.9 991.6 993.0 994.5 999.0 974.3 982.8 986.8 987.5 995.0 727.6 728.5 737.2 737.5 739.4 16128.6 16165.3 16534.5 16809.3 17120.3 14538.6 14727.6 14775.5 14922.1 15351.1 10408.8 11396.5 11613.8 11646.9 12301.0 3008.0 3014.9 3015.3 3057.8 3157.6 96622.0 113439.3 114207.0 115295.0 119763.0 11197.4 11297.8 11393.8 11409.3 11464.3 24352.6 24573.9 24828.0 25017.1 25031.4 7602.0 7804.5 7927.7 7931.9 8102.2 4110.8 4190.5 4196.7 4206.8 4220.9 4001.4 4058.9 4102.2 4103.7 4119.1 835.4 837.8 839.0 861.3 866.0 523.5 552.0 565.6 570.5 585.5 9565.4 9604.7 10184.7 10295.7 10398.1 535.2 544.3 574.2 578.7 580.5 3737.9 4511.5 4734.1 4785.0 4794.2 94324.0 98285.5 101491.0 102052.5 104737.0 57239.7 59805.0 64595.7 69391.7 90985.5 60935.7 61867.4 63381.7 66877.7 69796.7 9056.6 10600.5 10683.4 10767.7 10812.3 29932.3 33597.2 33610.8 34217.7 34883.7 36271.7 42943.6 44601.6 44949.0 45982.3 12133.5 12684.9 12995.9 13123.3 13658.9 13280.5 14057.6 14247.4 14547.6 14932.3 13242.8 13366.9 13562.0 13626.7 13646.5 10396.0 11746.9 13010.2 13170.6 13426.8 10405.5 12719.1 12752.9 12822.9 13305.3 10309.9 10947.9 10968.2 11110.0 11184.3 7924.0 10441.8 10903.5 10921.0 11010.9 09:43:29 INFO - PID 1926 | -------- Summary: end -------- 09:43:29 INFO - PID 1926 | 09:43:30 INFO - TEST-INFO | 1926: exit 0 09:43:30 INFO - TEST-OK | dromaeo_css | took 1117274ms 09:43:30 INFO - TEST-START | kraken 09:43:30 INFO - operating with platform_type : mac_ 09:43:30 INFO - Initialising browser for kraken test... 09:43:30 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpEqVHHm/profile http://localhost:49497/getInfo.html) 09:43:33 INFO - PID 1932 | 2017-03-22 09:43:33.008 plugin-container[1933:15060] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 09:43:33 INFO - PID 1932 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:43:33 INFO - PID 1932 | 2017-03-22 09:43:33.013 plugin-container[1933:15060] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f1f, name = 'com.apple.CFPasteboardClient' 09:43:33 INFO - PID 1932 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:43:33 INFO - PID 1932 | 2017-03-22 09:43:33.013 plugin-container[1933:15060] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:43:33 INFO - PID 1932 | __metrics Screen width/height:1600/1200 09:43:33 INFO - PID 1932 | colorDepth:24 09:43:33 INFO - PID 1932 | Browser inner width/height: 1024/689 09:43:33 INFO - PID 1932 | __metrics 09:43:33 ERROR - PID 1932 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:43:33 INFO - TEST-INFO | 1932: exit 0 09:43:33 INFO - Browser initialized. 09:43:33 INFO - Running cycle 1/1 for kraken test... 09:43:33 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmpEqVHHm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:43:36 INFO - PID 1934 | 2017-03-22 09:43:36.315 plugin-container[1935:15181] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 09:43:36 INFO - PID 1934 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:43:36 INFO - PID 1934 | 2017-03-22 09:43:36.315 plugin-container[1935:15181] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.CFPasteboardClient' 09:43:36 INFO - PID 1934 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:43:36 INFO - PID 1934 | 2017-03-22 09:43:36.315 plugin-container[1935:15181] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:43:46 INFO - PID 1934 | RSS: Main: 227196928 09:43:46 INFO - PID 1934 | 09:43:48 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49497/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49497/tests/kraken/driver.html?testName=audio-beat-detection) 09:43:49 INFO - PID 1934 | RSS: Main: 241831936 09:43:49 INFO - PID 1934 | 09:43:52 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49497/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49497/tests/kraken/driver.html?testName=audio-dft) 09:43:53 INFO - PID 1934 | RSS: Main: 250429440 09:43:53 INFO - PID 1934 | 09:43:56 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49497/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49497/tests/kraken/driver.html?testName=audio-fft) 09:43:57 INFO - PID 1934 | RSS: Main: 253321216 09:43:57 INFO - PID 1934 | 09:43:59 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49497/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49497/tests/kraken/driver.html?testName=audio-oscillator) 09:44:00 INFO - PID 1934 | RSS: Main: 254341120 09:44:00 INFO - PID 1934 | 09:44:02 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49497/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49497/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:44:02 INFO - PID 1934 | RSS: Main: 256016384 09:44:02 INFO - PID 1934 | 09:44:05 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49497/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49497/tests/kraken/driver.html?testName=imaging-darkroom) 09:44:06 INFO - PID 1934 | RSS: Main: 261181440 09:44:06 INFO - PID 1934 | 09:44:09 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49497/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49497/tests/kraken/driver.html?testName=imaging-desaturate) 09:44:10 INFO - PID 1934 | RSS: Main: 261709824 09:44:10 INFO - PID 1934 | 09:44:13 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49497/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49497/tests/kraken/driver.html?testName=json-parse-financial) 09:44:13 INFO - PID 1934 | RSS: Main: 261804032 09:44:13 INFO - PID 1934 | 09:44:15 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49497/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49497/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:44:16 INFO - PID 1934 | RSS: Main: 261529600 09:44:16 INFO - PID 1934 | 09:44:18 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49497/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49497/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:44:19 INFO - PID 1934 | RSS: Main: 261447680 09:44:19 INFO - PID 1934 | 09:44:21 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49497/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49497/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:44:22 INFO - PID 1934 | RSS: Main: 261554176 09:44:22 INFO - PID 1934 | 09:44:25 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49497/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49497/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:44:25 INFO - PID 1934 | RSS: Main: 261935104 09:44:25 INFO - PID 1934 | 09:44:29 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49497/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49497/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:44:29 INFO - PID 1934 | RSS: Main: 261578752 09:44:29 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49497/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49497/tests/kraken/driver.html?testName=ai-astar) 09:44:31 INFO - PID 1934 | RSS: Main: 264527872 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | __start_tp_report 09:44:31 INFO - PID 1934 | _x_x_mozilla_page_load 09:44:31 INFO - PID 1934 | _x_x_mozilla_page_load_details 09:44:31 INFO - PID 1934 | |i|pagename|runs| 09:44:31 INFO - PID 1934 | |0;ai-astar;104;124;166;134;117;114;117;117;114;116 09:44:31 INFO - PID 1934 | |1;audio-beat-detection;184;101;147;147;155;153;136;151;167;153 09:44:31 INFO - PID 1934 | |2;audio-dft;200;213;220;288;241;286;221;195;195;201 09:44:31 INFO - PID 1934 | |3;audio-fft;90;85;84;92;59;59;60;60;77;60 09:44:31 INFO - PID 1934 | |4;audio-oscillator;90;104;120;73;72;71;85;71;72;87 09:44:31 INFO - PID 1934 | |5;imaging-gaussian-blur;88;89;90;97;89;89;88;93;91;89 09:44:31 INFO - PID 1934 | |6;imaging-darkroom;146;108;154;153;96;95;97;96;96;102 09:44:31 INFO - PID 1934 | |7;imaging-desaturate;90;91;97;89;92;92;92;80;141;147 09:44:31 INFO - PID 1934 | |8;json-parse-financial;65;76;95;104;72;56;91;60;60;76 09:44:31 INFO - PID 1934 | |9;json-stringify-tinderbox;69;68;85;92;94;65;57;68;86;88 09:44:31 INFO - PID 1934 | |10;stanford-crypto-aes;81;76;103;107;108;108;84;84;85;86 09:44:31 INFO - PID 1934 | |11;stanford-crypto-ccm;143;166;150;155;153;149;165;140;198;149 09:44:31 INFO - PID 1934 | |12;stanford-crypto-pbkdf2;189;189;189;228;248;190;186;189;190;189 09:44:31 INFO - PID 1934 | |13;stanford-crypto-sha256-iterative;68;68;70;67;70;70;66;70;70;71 09:44:31 INFO - PID 1934 | __end_tp_report 09:44:31 INFO - PID 1934 | __start_cc_report 09:44:31 INFO - PID 1934 | _x_x_mozilla_cycle_collect,1024 09:44:31 INFO - PID 1934 | __end_cc_report 09:44:31 INFO - PID 1934 | __startTimestamp1490201071661__endTimestamp 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | ------- Summary: start ------- 09:44:31 INFO - PID 1934 | Number of tests: 14 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | [#0] ai-astar Cycles:10 Average:122.30 Median:117.00 stddev:17.15 (14.7%) stddev-sans-first:16.86 09:44:31 INFO - PID 1934 | Values: 104.0 124.0 166.0 134.0 117.0 114.0 117.0 117.0 114.0 116.0 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | [#1] audio-beat-detection Cycles:10 Average:149.40 Median:153.00 stddev:21.34 (14.0%) stddev-sans-first:18.61 09:44:31 INFO - PID 1934 | Values: 184.0 101.0 147.0 147.0 155.0 153.0 136.0 151.0 167.0 153.0 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | [#2] audio-dft Cycles:10 Average:226.00 Median:220.50 stddev:35.15 (15.9%) stddev-sans-first:36.01 09:44:31 INFO - PID 1934 | Values: 200.0 213.0 220.0 288.0 241.0 286.0 221.0 195.0 195.0 201.0 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | [#3] audio-fft Cycles:10 Average:72.60 Median:80.50 stddev:14.25 (17.7%) stddev-sans-first:13.66 09:44:31 INFO - PID 1934 | Values: 90.0 85.0 84.0 92.0 59.0 59.0 60.0 60.0 77.0 60.0 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | [#4] audio-oscillator Cycles:10 Average:84.50 Median:72.50 stddev:16.62 (22.9%) stddev-sans-first:17.51 09:44:31 INFO - PID 1934 | Values: 90.0 104.0 120.0 73.0 72.0 71.0 85.0 71.0 72.0 87.0 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:89.50 stddev:2.79 (3.1%) stddev-sans-first:2.83 09:44:31 INFO - PID 1934 | Values: 88.0 89.0 90.0 97.0 89.0 89.0 88.0 93.0 91.0 89.0 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | [#6] imaging-darkroom Cycles:10 Average:114.30 Median:95.50 stddev:25.70 (26.9%) stddev-sans-first:24.57 09:44:31 INFO - PID 1934 | Values: 146.0 108.0 154.0 153.0 96.0 95.0 97.0 96.0 96.0 102.0 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | [#7] imaging-desaturate Cycles:10 Average:101.10 Median:91.50 stddev:23.05 (25.2%) stddev-sans-first:24.09 09:44:31 INFO - PID 1934 | Values: 90.0 91.0 97.0 89.0 92.0 92.0 92.0 80.0 141.0 147.0 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | [#8] json-parse-financial Cycles:10 Average:75.50 Median:74.00 stddev:16.39 (22.1%) stddev-sans-first:16.93 09:44:31 INFO - PID 1934 | Values: 65.0 76.0 95.0 104.0 72.0 56.0 91.0 60.0 60.0 76.0 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | [#9] json-stringify-tinderbox Cycles:10 Average:77.20 Median:85.50 stddev:13.12 (15.3%) stddev-sans-first:13.58 09:44:31 INFO - PID 1934 | Values: 69.0 68.0 85.0 92.0 94.0 65.0 57.0 68.0 86.0 88.0 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:82.50 stddev:12.68 (15.4%) stddev-sans-first:12.79 09:44:31 INFO - PID 1934 | Values: 81.0 76.0 103.0 107.0 108.0 108.0 84.0 84.0 85.0 86.0 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | [#11] stanford-crypto-ccm Cycles:10 Average:156.80 Median:154.00 stddev:16.69 (10.8%) stddev-sans-first:16.94 09:44:31 INFO - PID 1934 | Values: 143.0 166.0 150.0 155.0 153.0 149.0 165.0 140.0 198.0 149.0 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.70 Median:189.50 stddev:21.27 (11.2%) stddev-sans-first:22.27 09:44:31 INFO - PID 1934 | Values: 189.0 189.0 189.0 228.0 248.0 190.0 186.0 189.0 190.0 189.0 09:44:31 INFO - PID 1934 | 09:44:31 INFO - PID 1934 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:70.00 stddev:1.63 (2.3%) stddev-sans-first:1.69 09:44:31 INFO - PID 1934 | Values: 68.0 68.0 70.0 67.0 70.0 70.0 66.0 70.0 70.0 71.0 09:44:31 INFO - PID 1934 | -------- Summary: end -------- 09:44:31 INFO - PID 1934 | 09:44:32 INFO - TEST-INFO | 1934: exit 0 09:44:32 INFO - TEST-OK | kraken | took 62338ms 09:44:32 INFO - SUITE-END | took 1179s 09:44:32 INFO - Completed test suite (00:19:39) 09:44:32 INFO - javascript benchmark 09:44:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7578.5157711964675, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2839.730225628565, 3541.344851202526, 3586.2827434513106, 3587.0494319005484, 3636.709063274938, 6121.938780612192, 6144.293406258282, 6171.352008039158, 6199.597026193294, 6255.249370075586, 5979.192808970788, 6094.847628809282, 6108.541859360555, 6134.008014331981, 6172.197614310145, 4557.339185818071, 5443.156310771837, 5580.86047848803, 5633.577481688869, 5650.915236271459, 4760.523947605247, 5624.0596994037, 5639.2668953036045, 5668.149777533383, 5832.300123985127, 5520.972395137998, 5687.914681279784, 5703.5152012079025, 5741.167530708045, 5761.0, 5444.40111587725, 5609.214709940612, 5627.6060675752715, 5670.489655930986, 5703.3441154267375, 5560.582956278296, 5572.749226284827, 5588.329400471969, 5608.186812912146, 5614.1859430382365, 5990.97004514979, 6244.906326405107, 6265.87468250637, 6272.1219029335925, 6282.968585157045, 6423.807285781434, 6501.609903405763, 6629.436497897685, 6702.09521714572, 6749.291324410915, 6748.460123190133, 6770.796876093725, 6771.085903403026, 6773.254941956381, 6829.214640316378, 6320.905186422207, 6322.209723784527, 6326.588771729823, 6348.841278968062, 6354.142190804275, 5506.256655351556, 5587.413321601254, 6716.798496045127, 6765.52641315113, 6850.691718872663, 5157.510036546522, 6347.619142851444, 6428.0, 6595.0, 6600.471962243009, 6509.707063182168, 6670.299618540081, 6757.3242675732035, 6768.69540870662, 6776.627285499247, 5673.744681489343, 6667.899981500282, 6674.365935236145, 6679.298673639295, 6697.095892054608, 6174.104754810527, 6331.145295385064, 6374.617522948638, 6699.229588597374, 6720.798376048726, 4756.1438940990265, 5281.313429254173, 5298.496642818926, 5303.655262407931, 5322.600804939645, 4696.966667333181, 4708.670393072452, 4719.126961512131, 4726.621870250372, 4762.595179409789, 6220.253569571681, 6285.497160227171, 6329.145565348711, 6342.0, 6361.109444677749, 6714.764983225564, 6778.356056174655, 6799.0, 6808.829779255559, 6819.965900170567, 6333.1766870306565, 6350.872982540323, 6366.395192456709, 6402.231732192074, 6418.390252925965, 4445.1998640245065, 5568.136938774496, 5653.491185793216, 6002.639841609517, 6014.127951447016, 5790.594658373873, 6856.6228857413325, 6881.277465866212, 6926.376626103473, 6961.095057642474, 5669.85825354353, 6668.899966500409, 6815.556988795713, 6818.28408017151, 6828.146481689789, 5714.914276285942, 6935.445164386938, 6940.09778728762, 6954.443644508326, 6995.580265184105, 5448.0, 6445.16212892321, 6482.611043337415, 6515.478761698958, 6643.900341494785, 6721.0, 6727.966360168168, 6737.696803643946, 6742.494312926452, 6753.932460675331, 5570.275864137636, 6373.74505019794, 6546.083548303146, 6551.508636852351, 6557.442617377576, 6345.428911397997, 6454.709538070704, 6519.3480651936325, 6559.245686746085, 6576.80269591913, 6245.28179259391, 6398.936010639834, 6444.0, 6456.192975878015, 6469.0, 6476.384743449365, 6577.309382514958, 6605.306442823627, 6607.702653380706, 6640.468762498892, 6226.501879849692, 6288.717007734572, 6354.968225159029, 6399.488040956619, 6439.227292724903], "value": 6061.58649073043, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.2286484866647, 707.8690442268179, 738.47937204271, 1271.1610337177458, 1271.6566527037705, 37041.0, 38044.0, 38174.0, 38197.472101115956, 38231.808840955826, 36773.0, 37080.81459592705, 38176.809115954384, 38273.425898611546, 38324.425133623015, 8021.077576078753, 8218.917810821875, 8395.95802020989, 8636.654533818672, 8645.092265312145, 8033.6384862681025, 8377.45546539476, 8611.698590549331, 8736.170063843956, 8865.024847266795, 7567.508111972704, 8789.560521973908, 8809.559522023905, 8879.023307436177, 8879.200871921526, 8788.8681669775, 8832.735017949472, 8847.203751662317, 8847.646094156225, 8864.379493435463, 8609.61256743448, 8647.740567782977, 8674.002489713637, 8680.0, 8683.305335573139, 31239.84380078085, 37878.0, 37899.81050094732, 38154.42768358477, 38298.61701382978, 2968.5695574141846, 2979.865906034234, 2980.0, 2990.2225421390376, 3011.4278287125376, 27746.167614971582, 27816.860915695495, 27939.44121117566, 28846.711532884612, 28936.27659308513, 2655.5352813257605, 2661.718027045051, 2704.445588654321, 2708.133397312861, 2765.6681198256338, 315426.0, 319937.0, 320206.79793202237, 320699.0, 320956.0, 49143.26285105726, 49249.26126108111, 49367.25949110766, 49425.75287123613, 49685.50314496881, 51623.451296461164, 51630.48369516257, 51904.48095519072, 52213.73893130586, 52226.477735222164, 14289.214093224973, 14575.0, 14620.780688289684, 14654.754345880507, 14687.26563671812, 9791.804163916824, 11553.884461155281, 11649.359285239394, 11883.465244064038, 11895.643130706092, 13318.200907945555, 13657.317134143452, 13704.90360771136, 13803.585892423243, 13889.02776805614, 5926.644401335933, 5937.168796368512, 5966.0, 5975.073863551156, 6000.759969601254, 847.6270441005938, 854.4019186569426, 971.5288085278635, 1003.3027046202819, 1027.5992362978445, 22503.0, 23057.0, 24083.03667853301, 24088.87955560246, 24129.879350603485, 989.3322007644811, 1041.9426931518688, 1122.9213955023233, 1132.4620805117504, 1138.4194061028934, 9337.393069450463, 10772.461376931122, 12246.571370002008, 12288.139830211978, 12302.81545776814, 314491.0, 374308.0, 375564.0, 377697.0, 378069.0, 39724.0, 40501.0, 44530.33204501935, 54072.0, 55163.724181379635, 1319.8746119118669, 1508.3815635589356, 1515.6514001779653, 1523.7333466643292, 1531.9429593580394, 20547.0, 26257.868710656323, 26289.342766430993, 26897.327566810985, 28373.0, 25991.870040649676, 35021.949341519794, 35955.741549045655, 36386.45420318644, 36562.0, 321681.0, 323477.0, 324388.0, 325696.0, 326973.0, 30906.69093309038, 31433.52849707302, 31786.682133178372, 31794.84102579472, 31809.363812724077, 39023.0, 40537.59462405338, 40591.39112913368, 41193.58806411897, 41278.0, 33915.98252052443, 34106.82946585251, 34566.65433345635, 34783.4782478258, 35262.94211173669, 33921.0, 33998.49002264919, 34848.65151348454, 35020.57181136928, 35171.29657406786, 34282.314353713286, 34517.309653807286, 34778.82610586931, 34888.65111348854, 35056.649433505336, 27145.59281610817, 28093.0, 28752.85623571869, 30368.544471832498, 30613.23466913256, 23041.308760737204, 23260.0, 23427.828608569846, 23493.53012939766, 23625.173118940755], "value": 17347.770691313202, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.54785653279254, 427.19473791902254, 435.6345143112746, 436.93882856400097, 457.81687325069987, 19580.41258762236, 20541.794582054175, 21311.467213319676, 21817.01823417946, 21937.34187974363, 21394.35816925491, 22581.774182258174, 22724.886375568098, 23007.194748183818, 23182.072717091298, 5545.140503222007, 6126.938730612681, 6360.300366959631, 6361.74553017881, 6371.0, 5114.744262786846, 5922.555808314372, 6327.303996560375, 6332.271788744308, 6333.651649159295, 4720.693154944935, 6279.466245369149, 6287.308396076428, 6302.905456418157, 6311.968440157792, 6178.691065446732, 6276.152719382916, 6281.59169653971, 6291.968540157316, 6294.339094395092, 5321.122014867542, 5509.201165830932, 5554.72226388679, 5562.443755624446, 5618.381977982419, 26831.06091286815, 27267.727322726718, 28513.144605661862, 28561.0, 28720.138395848157, 1956.158851693771, 1991.6315481635943, 2008.839292856568, 2019.8889061101634, 2034.735484387035, 15398.538043858702, 16389.508314750576, 16599.087050212354, 16666.66666666676, 16787.832121678748, 1712.7944646642359, 1714.6913555559995, 1729.3860679458862, 1729.5848996240939, 1735.817739137385, 130223.0, 137537.0, 143962.0, 151111.0, 151217.0, 12513.061520386007, 12756.936215318863, 12983.805242921364, 13045.412956416983, 13302.201867887958, 15979.360825567079, 16222.432214872422, 16249.350025998827, 16251.0, 16304.266308016167, 8024.438289319692, 8734.737957861274, 8735.1701588349, 9111.225545828678, 9180.632774688938, 7160.57036577807, 8641.611127499278, 8645.870311945326, 8664.0, 8850.300658299151, 7866.7639970800965, 7993.681042628017, 8010.3992200584025, 8020.79948001293, 8052.516848989079, 1957.8350881225647, 2130.0521268035886, 2227.3874684461903, 2234.731832180149, 2245.180509114194, 856.8133135607229, 933.5518950903604, 941.18117238002, 941.5762906692016, 981.79873126009, 15779.684406312039, 20235.595288094155, 20726.481837954172, 20808.687869681966, 20854.478638033866, 843.5824266987757, 939.8449255872841, 957.7844984878486, 961.6682244625594, 971.844504879216, 8402.655575107954, 9952.552135153936, 10798.352098874093, 10804.351738895692, 10839.512221950032, 505774.0, 582680.0, 627023.0, 628180.0, 634946.0, 119048.0, 146634.0, 150441.24779376033, 150506.0, 152297.23851380672, 1177.1433078318566, 1181.2085902445383, 1205.6081773423673, 1208.5769980506752, 1209.5585111434264, 12155.756884862076, 17513.124343782503, 17692.557686057953, 17952.820471795636, 18015.2793888243, 42788.0, 44650.77674611606, 57416.71291643515, 59287.70356148192, 59561.0, 75185.62407187928, 75664.48671026439, 77466.22533774815, 77641.0, 79004.60497697475, 8639.222469977733, 8666.913330866611, 8674.956625216833, 8735.650573977224, 8831.116888311375, 13599.79600306016, 13622.182669039888, 14019.0, 14060.718785624435, 14211.218382988622, 9862.161716254199, 11255.606053788406, 11266.774664506827, 11506.30962142274, 11532.0, 9656.95171524138, 11036.006759391694, 11161.386123763272, 11248.156388270749, 11502.94248528752, 9164.908350916405, 10181.083702466814, 11630.53477860876, 11689.590864319707, 11874.346910919983, 7276.854462910818, 8582.270507006975, 8845.934626917351, 8911.866322005304, 8987.370884038317, 8540.444871083608, 9878.901210988086, 11702.88297117018, 11857.288562686266, 11860.76278474443], "value": 10146.588806304588, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.1265883505139, 1393.2128347483665, 1406.4936622815792, 1417.3834382043813, 1438.654722866512, 10327.328723632958, 10582.36505809652, 10727.570897164114, 10753.83869241962, 10803.675889723301, 10452.581896724141, 10720.571177152924, 10720.678379648603, 10724.624638137666, 10774.97637724417, 2424.624183251599, 2442.4748679034005, 2447.0089613706477, 2453.0555736041665, 2453.4970331082172, 2187.752980800944, 2393.569157551649, 2432.805375569959, 2441.5727247731666, 2442.8167887408517, 2383.2969274064194, 2426.9271921842374, 2438.3538362333998, 2441.853488790678, 2443.6701045358914, 2053.784352642966, 2352.3295860679623, 2397.7722116398913, 2404.4589967257225, 2411.6141417373224, 2350.9647355289685, 2426.0538390027905, 2435.561598912195, 2440.6583078369044, 2463.8398504097177, 9221.492817895012, 10842.0, 10933.617323393722, 10949.45252737368, 10972.23194376386, 5359.598030147755, 5392.973035134838, 6138.9693051534905, 6391.0, 6432.742690292383, 3869.400242962345, 4057.756534607933, 4082.2447847148073, 4123.0, 4132.111596006873, 1189.2329447506397, 1500.7898894154828, 1544.3822471011572, 1565.506865337415, 1566.1073188282683, 13454.327283635877, 13657.453701851913, 14412.0, 14571.70856582873, 14590.562283131523, 5052.115879721034, 5085.237214417867, 5366.7048312343195, 5372.435894231127, 5405.945940540544, 11219.60731374398, 11255.268407553647, 11287.887121128684, 11313.83029254562, 11319.490622921823, 6211.472024877846, 6230.7819226327765, 6236.844078898064, 6241.906371404432, 6252.437280644764, 5147.819826306061, 5476.972615136899, 5491.780328786804, 5758.971205143947, 5847.6783776891825, 4161.9583804161575, 4195.202911446815, 4257.318828987409, 4280.6789490788315, 4309.159713855831, 1231.5689508671894, 1260.4391045984632, 1268.1123213750227, 1278.778766278202, 1281.5066199513255, 598.9368870255331, 663.8439966607843, 708.4403321376158, 727.0983979865035, 757.6666266842572, 5937.376575459514, 6356.523260755462, 6408.391202835724, 6597.340265973365, 6598.967005164944, 564.5765675743193, 584.4097461563852, 597.2564157623699, 598.3956204233755, 598.4195330529379, 3221.291315910496, 3513.402721537345, 3529.241213139136, 3740.784245120347, 3747.6252374762307, 13411.932940335431, 13706.931465342808, 13768.380422880995, 13895.235762033186, 13920.721585568435, 13493.122947008413, 13502.189868607718, 13658.658533536742, 13704.10923289983, 13739.793903091462, 10071.295009349275, 10153.593856245667, 10184.694459166238, 10203.438810865473, 10285.382877027403, 5904.232449781501, 6470.676466176767, 6643.0, 6686.966565167143, 6736.292689267556, 11746.88253117492, 12080.973117284777, 12146.331951742386, 12194.51221951112, 12286.75426491483, 11926.284422934652, 12078.154529182873, 12309.830566096207, 12490.500379984698, 12547.686307842378, 8695.52174630376, 8809.647614095364, 8894.86657700148, 8924.553772311228, 8927.955360223417, 9907.653232136841, 10009.74975625586, 10066.396016239049, 10107.494625268855, 10173.237007224636, 7415.369693575891, 7429.368503677248, 8856.911430885866, 8867.600957957036, 8989.056149104512, 8509.957450212709, 8721.956390218009, 8730.47617142948, 8772.078931712334, 8831.381803273709, 7361.079865016873, 7685.923140768745, 7697.730579429693, 7734.883976740466, 7792.337651299703, 5721.656700597978, 5748.770049197985, 5787.334456537552, 5811.56413268998, 5857.355690874086, 5836.503897168788, 5961.672108034099, 5999.040153575407, 6044.395560443921, 6110.51115910735], "value": 5053.9778129665965, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.2079272125064, 390.2254025758866, 399.7162014969372, 406.87183537185786, 410.6612045062823, 5347.652402593829, 6041.093835924608, 6210.937890621092, 6322.43098121169, 6338.429541341278, 5399.487048730374, 6156.630602163873, 6169.938300616993, 6238.656873871935, 6274.3098259191565, 3802.9619703802887, 4495.617872480843, 4557.179707652621, 4677.906441871178, 4690.460597031334, 4020.5778393268724, 4674.626029917598, 4680.625549955996, 4681.438227412716, 4681.648876334271, 3611.2235869288097, 4332.938430084634, 4380.430544029272, 4402.911941761163, 4570.794314255866, 3794.886153415388, 4364.388985542027, 4588.632909367243, 4655.207745018159, 4669.392978912737, 3762.172322089136, 3939.212157568498, 3941.4628294964987, 3954.9011274718073, 3964.108075682977, 4655.4180727409075, 6060.181875446847, 6076.605020673642, 6088.269407671063, 6104.26748790148, 5337.893242135136, 5418.91871621926, 5441.830006548572, 5481.917771233435, 5487.396386397493, 5552.222688823568, 6276.592021518587, 6678.933210667879, 6685.699143538553, 6767.864642707167, 4433.933490997638, 4437.001674623184, 4447.17727220462, 4447.533009034038, 4455.933161002587, 6603.14159159311, 6782.321767823179, 7027.754028608974, 7107.502474826764, 7137.750178743719, 4298.946758044237, 4388.956110438855, 4517.10905020787, 4524.298733696282, 4547.613452856544, 4011.5988401159652, 4027.194561087794, 4610.9308360374625, 4633.768311584475, 4639.86080417588, 5214.817481388133, 5387.380451248078, 5442.265294185234, 5458.77177635029, 5487.42382049887, 5282.392524859613, 6120.969395152996, 6244.875102497925, 6785.541108661661, 6790.898136527956, 1239.6405042537745, 1447.2835946206694, 1565.1939251285596, 1593.7370333894778, 1639.9754003689716, 1017.3031473440569, 1017.7608262058559, 1029.4543891737392, 1038.2265212416646, 1047.2930771729052, 5205.55752761012, 5792.420757924174, 5964.85087872798, 6450.967745161244, 6459.12219879907, 5958.0, 6926.896096558556, 6995.8456854619435, 7003.439724822002, 7054.435645148375, 4916.8770780730765, 6037.72830222641, 6432.35676432353, 6469.641375311237, 6562.901556476561, 3166.3825554017208, 3593.3531964246686, 3654.5016543217153, 3678.3930651442747, 3692.51997240357, 5633.8591535211945, 7073.434125269966, 7183.46124040699, 7191.820204494825, 7199.9280007200305, 6673.299303573055, 6871.725130994804, 7065.290722370693, 7168.92831071693, 7210.170830354471, 5602.803901863415, 7017.614031228331, 7055.7177712891935, 7181.96409017962, 7194.136703595602, 6663.0, 6871.9656401717675, 6969.616671083139, 7017.333353331425, 7087.149542054986, 6022.915875142341, 6520.185691370411, 6846.863062738617, 6878.449724021966, 6883.621400822803, 6807.591544507345, 6946.305369463013, 7096.542146646527, 7235.565866048054, 7238.457115716447, 3606.837692303905, 3769.754965927206, 4192.9161416770885, 4541.364209010675, 4542.977285113553, 5737.56968227393, 6535.3464653534265, 6780.643871225676, 7057.0472986146215, 7090.568394753279, 5795.739191736467, 6527.934720652733, 6794.3545363190415, 6815.318468153145, 6831.4629208427705, 4951.405831300123, 6469.191351081115, 6805.829854253683, 6806.6256355900905, 6830.0, 5531.695756733257, 6302.590331628429, 6358.141650877073, 6585.209774827052, 6671.299513551007, 5811.94188058114, 5827.76688932438, 5992.67040312787, 6544.018397240452, 6821.454283657196, 6027.914975304312, 6250.562460627712, 6414.61512309263, 6627.3372662733345, 6632.303608121078], "value": 4807.607869722461, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.6841579210395, 638.773235501397, 710.2081179484869, 716.892401240084, 730.4704089535086, 14837.925810370947, 15050.0, 15543.067415955022, 15632.14023228722, 15638.921805390986, 14919.850801491983, 15014.62463438412, 15041.398344066225, 15171.772423413657, 15206.619834504143, 916.3814425262938, 941.9293552983554, 943.5470973932516, 943.9056094390575, 945.3382632157517, 925.1719710858778, 952.6903756279237, 956.9473678947655, 959.2565761534796, 960.1262850805768, 973.8587904753806, 991.6430085169334, 992.9950350248239, 994.5176589354157, 998.9700308990742, 974.28390133252, 982.8280050991084, 986.7779749556364, 987.5407935310116, 994.9950250248738, 727.5634619228473, 728.4973368375856, 737.181728281607, 737.5058710663868, 739.3604532079767, 16128.59678508035, 16165.272562734704, 16534.503964881074, 16809.32762689491, 17120.315187392614, 14538.636534086627, 14727.568231819641, 14775.482858100022, 14922.104673719503, 15351.07893526392, 10408.84386734192, 11396.544138234462, 11613.767724645544, 11646.883531164665, 12301.031834906462, 3008.0, 3014.9246268843235, 3015.25593743916, 3057.770667199969, 3157.636871759731, 96622.0, 113439.29841052309, 114207.0, 115295.0, 119763.0, 11197.384143872003, 11297.830532542019, 11393.772124557463, 11409.25839820409, 11464.31214127155, 24352.634710479357, 24573.87713061459, 24828.00687972497, 25017.124400646255, 25031.37421564439, 7601.961990190013, 7804.453688241769, 7927.682892684344, 7931.920680793233, 8102.230288122619, 4110.815013324408, 4190.455240818735, 4196.664266858644, 4206.810693518799, 4220.873373798791, 4001.4397984282223, 4058.898527536835, 4102.179564087135, 4103.733257338264, 4119.134981653826, 835.415209353455, 837.7738010737189, 838.9580520973927, 861.2851333393269, 865.9826803464022, 523.4529916237541, 551.9613627046068, 565.6069032022787, 570.5150621971375, 585.4701495146899, 9565.378250413702, 9604.711858644252, 10184.694459166238, 10295.691129266133, 10398.116160126474, 535.1945322289929, 544.3331918399929, 574.1502576187182, 578.6788332475433, 580.5152697497637, 3737.850485980585, 4511.458624965026, 4734.07685501324, 4784.976075119672, 4794.184988551954, 94324.0, 98285.50857245667, 101491.0, 102052.48973755083, 104737.0, 57239.71380143073, 59805.0, 64595.677021615535, 69391.65304173446, 90985.54507227421, 60935.69532152311, 61867.381326186165, 63381.683091584244, 66877.66561167163, 69796.6510167456, 9056.592453339746, 10600.4699765013, 10683.412412317397, 10767.730806729895, 10812.297200682246, 29932.25169370696, 33597.16007099842, 33610.83194584109, 34217.65782342145, 34883.65116348804, 36271.730489432746, 42943.57056429521, 44601.55398445974, 44949.0, 45982.310265345375, 12133.514659413524, 12684.936575317064, 12995.935020324838, 13123.278219698008, 13658.863411365759, 13280.53518126861, 14057.648558786112, 14247.430102795772, 14547.636309091935, 14932.253387330808, 13242.80135797983, 13366.93316533411, 13562.015561985783, 13626.659333516742, 13646.454141834216, 10396.0, 11746.882531174579, 13010.21938683682, 13170.60488185356, 13426.798598020841, 10405.479726013518, 12719.109662323548, 12752.936235318764, 12822.935885320514, 13305.334733263104, 10309.896901030894, 10947.945260273966, 10968.23222374426, 11110.0, 11184.328940263611, 7924.0, 10441.791164176826, 10903.509342079467, 10921.0, 11010.889891101308], "value": 7307.371818153355, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1629.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 124.0, 166.0, 134.0, 117.0, 114.0, 117.0, 117.0, 114.0, 116.0], "value": 122.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 101.0, 147.0, 147.0, 155.0, 153.0, 136.0, 151.0, 167.0, 153.0], "value": 149.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 213.0, 220.0, 288.0, 241.0, 286.0, 221.0, 195.0, 195.0, 201.0], "value": 226.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 84.0, 92.0, 59.0, 59.0, 60.0, 60.0, 77.0, 60.0], "value": 72.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 104.0, 120.0, 73.0, 72.0, 71.0, 85.0, 71.0, 72.0, 87.0], "value": 84.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 90.0, 97.0, 89.0, 89.0, 88.0, 93.0, 91.0, 89.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 108.0, 154.0, 153.0, 96.0, 95.0, 97.0, 96.0, 96.0, 102.0], "value": 114.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 97.0, 89.0, 92.0, 92.0, 92.0, 80.0, 141.0, 147.0], "value": 101.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 95.0, 104.0, 72.0, 56.0, 91.0, 60.0, 60.0, 76.0], "value": 75.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 85.0, 92.0, 94.0, 65.0, 57.0, 68.0, 86.0, 88.0], "value": 77.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 76.0, 103.0, 107.0, 108.0, 108.0, 84.0, 84.0, 85.0, 86.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 166.0, 150.0, 155.0, 153.0, 149.0, 165.0, 140.0, 198.0, 149.0], "value": 156.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 189.0, 189.0, 228.0, 248.0, 190.0, 186.0, 189.0, 190.0, 189.0], "value": 198.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 67.0, 70.0, 70.0, 66.0, 70.0, 70.0, 71.0], "value": 69.0, "unit": "score"}]}]} 09:44:32 INFO - Return code: 0 09:44:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:44:33 ERROR - # TBPL SUCCESS # 09:44:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:44:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:44:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:44:33 INFO - 2017-03-22 09:44:33.024 screenresolution[1939:15567] starting screenresolution argv=screenresolution get 09:44:33 INFO - 2017-03-22 09:44:33.027 screenresolution[1939:15567] Display 0: 1600x1200x32@60 09:44:33 INFO - 2017-03-22 09:44:33.033 screenresolution[1940:15570] starting screenresolution argv=screenresolution list 09:44:33 INFO - Available Modes on Display 0 09:44:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:44:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:44:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:44:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:44:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:44:33 INFO - Intel Iris: 09:44:33 INFO - Chipset Model: Intel Iris 09:44:33 INFO - Type: GPU 09:44:33 INFO - Bus: Built-In 09:44:33 INFO - VRAM (Dynamic, Max): 1536 MB 09:44:33 INFO - Vendor: Intel (0x8086) 09:44:33 INFO - Device ID: 0x0a2e 09:44:33 INFO - Revision ID: 0x0009 09:44:33 INFO - Displays: 09:44:33 INFO - Display: 09:44:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:44:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:44:33 INFO - Display Serial Number: 1600x1200 60 09:44:33 INFO - Main Display: Yes 09:44:33 INFO - Mirror: Off 09:44:33 INFO - Online: Yes 09:44:33 INFO - Rotation: Supported 09:44:33 INFO - Return code: 0 09:44:33 INFO - Running post-action listener: _resource_record_post_action 09:44:33 INFO - [mozharness: 2017-03-22 16:44:33.265411Z] Finished run-tests step (success) 09:44:33 INFO - Running post-run listener: _resource_record_post_run 09:44:33 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 2033152; Write bytes: 514549760; Read time: 22; Write time: 1613 09:44:33 INFO - TinderboxPrint: CPU usage
54.0% 09:44:33 INFO - TinderboxPrint: I/O read bytes / time
2,033,152 / 22 09:44:33 INFO - TinderboxPrint: I/O write bytes / time
514,549,760 / 1,613 09:44:33 INFO - TinderboxPrint: CPU idle
2,208.4 (46.0%) 09:44:33 INFO - TinderboxPrint: CPU system
969.5 (20.2%) 09:44:33 INFO - TinderboxPrint: CPU user
1,627.8 (33.9%) 09:44:33 INFO - TinderboxPrint: Swap in / out
213,364,736 / 0 09:44:33 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 211710464; Write bytes: 213440512; Read time: 17331; Write time: 471 09:44:33 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 2033152; Write bytes: 299585536; Read time: 22; Write time: 1135 09:44:33 INFO - Running post-run listener: _upload_blobber_files 09:44:33 INFO - Blob upload gear active. 09:44:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:44:33 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 09:44:33 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:44:33 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:44:34 INFO - (blobuploader) - INFO - Open directory for files ... 09:44:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:44:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:44:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:34 INFO - (blobuploader) - INFO - Done attempting. 09:44:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:44:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:44:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:35 INFO - (blobuploader) - INFO - Done attempting. 09:44:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:44:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:44:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:36 INFO - (blobuploader) - INFO - Done attempting. 09:44:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:44:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:44:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:40 INFO - (blobuploader) - INFO - Done attempting. 09:44:40 INFO - (blobuploader) - INFO - Iteration through files over. 09:44:40 INFO - Return code: 0 09:44:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:44:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:44:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1cc1c9b5117d31530581734acc366bc7a4ef06fe7383aa23b5a9f3ebe754f56ca1d072266a7643911e8fc7db4fd6f562195f8cc0455eb0c5e3724982d5f0477", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b8889bb6e66f62fc7287b5e41a1f1576a1decf35a29bce99ac81583f7b0ad65f5087acb684e3dfb7cedf490ec01590ebb11e31621abdb329cbddbfc0bfc9850", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85eb65f886ccc8e58cff33e84e7f9b9c91c3cb2f53c1bd24b9f09458721da973da1af1f5c8955a20e4684aec823b389f0ce0be9fab0678f3407daff369302f70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0e253c7e81b0ad39501f411964a4a3c74b7c07e2fa274f25f5d87a040e4c6f2b6d88c494da4afbe04dd17b4513c5162672bbaf63ec9c5d42d20305e111c40d8"} 09:44:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:44:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:44:40 INFO - Contents: 09:44:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1cc1c9b5117d31530581734acc366bc7a4ef06fe7383aa23b5a9f3ebe754f56ca1d072266a7643911e8fc7db4fd6f562195f8cc0455eb0c5e3724982d5f0477", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b8889bb6e66f62fc7287b5e41a1f1576a1decf35a29bce99ac81583f7b0ad65f5087acb684e3dfb7cedf490ec01590ebb11e31621abdb329cbddbfc0bfc9850", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85eb65f886ccc8e58cff33e84e7f9b9c91c3cb2f53c1bd24b9f09458721da973da1af1f5c8955a20e4684aec823b389f0ce0be9fab0678f3407daff369302f70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0e253c7e81b0ad39501f411964a4a3c74b7c07e2fa274f25f5d87a040e4c6f2b6d88c494da4afbe04dd17b4513c5162672bbaf63ec9c5d42d20305e111c40d8"} 09:44:40 INFO - Running post-run listener: copy_logs_to_upload_dir 09:44:40 INFO - Copying logs to upload dir... 09:44:40 INFO - mkdir: /builds/slave/test/build/upload/logs 09:44:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.386005 ========= master_lag: 1.39 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-22 09:44:41.618631) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 09:44:41.622153) ========= 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.iZbRGZz2r9/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.mFsCBM0sup/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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/Firefox/sha512/a1cc1c9b5117d31530581734acc366bc7a4ef06fe7383aa23b5a9f3ebe754f56ca1d072266a7643911e8fc7db4fd6f562195f8cc0455eb0c5e3724982d5f0477", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b8889bb6e66f62fc7287b5e41a1f1576a1decf35a29bce99ac81583f7b0ad65f5087acb684e3dfb7cedf490ec01590ebb11e31621abdb329cbddbfc0bfc9850", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85eb65f886ccc8e58cff33e84e7f9b9c91c3cb2f53c1bd24b9f09458721da973da1af1f5c8955a20e4684aec823b389f0ce0be9fab0678f3407daff369302f70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0e253c7e81b0ad39501f411964a4a3c74b7c07e2fa274f25f5d87a040e4c6f2b6d88c494da4afbe04dd17b4513c5162672bbaf63ec9c5d42d20305e111c40d8"} build_url:https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011147 build_url: 'https://queue.taskcluster.net/v1/task/XVVgqD14SWmwLMjWbhnrCQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1cc1c9b5117d31530581734acc366bc7a4ef06fe7383aa23b5a9f3ebe754f56ca1d072266a7643911e8fc7db4fd6f562195f8cc0455eb0c5e3724982d5f0477", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b8889bb6e66f62fc7287b5e41a1f1576a1decf35a29bce99ac81583f7b0ad65f5087acb684e3dfb7cedf490ec01590ebb11e31621abdb329cbddbfc0bfc9850", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85eb65f886ccc8e58cff33e84e7f9b9c91c3cb2f53c1bd24b9f09458721da973da1af1f5c8955a20e4684aec823b389f0ce0be9fab0678f3407daff369302f70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0e253c7e81b0ad39501f411964a4a3c74b7c07e2fa274f25f5d87a040e4c6f2b6d88c494da4afbe04dd17b4513c5162672bbaf63ec9c5d42d20305e111c40d8"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 09:44:41.658404) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2017-03-22 09:44:41.658738) ========= 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.iZbRGZz2r9/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.mFsCBM0sup/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.005096 ========= master_lag: 2.77 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2017-03-22 09:44:44.433039) ========= ========= Started reboot skipped (results: 3, elapsed: 2 secs) (at 2017-03-22 09:44:44.433367) ========= ========= Finished reboot skipped (results: 3, elapsed: 2 secs) (at 2017-03-22 09:44:46.765227) ========= ========= Total master_lag: 4.26 =========