builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0060 starttime: 1491480697.61 results: success (0) buildid: 20170406035133 builduid: 7a516864ab5a43b98616afdcb65a46b3 revision: 950612071c4e5cbd61af6c0f66ed0bc40c35e39c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 05:11:37.613420) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 05:11:37.613881) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 05:11:37.614168) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 05:11:37.641068) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:11:37.641697) ========= 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.aP6adHBFKU/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.12swxwcZKm/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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-04-06 05:11:37-- 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% 155M=0s 2017-04-06 05:11:37 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.158235 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:11:37.823880) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:11:37.824208) ========= 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.aP6adHBFKU/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.12swxwcZKm/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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.027283 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:11:37.873089) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 05:11:37.873484) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 950612071c4e5cbd61af6c0f66ed0bc40c35e39c --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 950612071c4e5cbd61af6c0f66ed0bc40c35e39c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aP6adHBFKU/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.12swxwcZKm/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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-04-06 05:11:37,984 truncating revision to first 12 chars 2017-04-06 05:11:37,984 Setting DEBUG logging. 2017-04-06 05:11:37,984 attempt 1/10 2017-04-06 05:11:37,984 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/950612071c4e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 05:11:39,179 unpacking tar archive at: mozilla-central-950612071c4e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.473909 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 05:11:39.371612) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 05:11:39.372335) ========= script_repo_revision: 950612071c4e5cbd61af6c0f66ed0bc40c35e39c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 05:11:39.372767) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 05:11:39.373051) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 05:11:39.393314) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-06 05:11:39.393938) ========= /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.aP6adHBFKU/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.12swxwcZKm/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 05:11:39 INFO - MultiFileLogger online at 20170406 05:11:39 in /builds/slave/test 05:11:39 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 05:11:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:11:39 INFO - {'append_to_log': False, 05:11:39 INFO - 'base_work_dir': '/builds/slave/test', 05:11:39 INFO - 'blob_upload_branch': 'Firefox', 05:11:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:11:39 INFO - 'branch': 'Firefox', 05:11:39 INFO - 'buildbot_json_path': 'buildprops.json', 05:11:39 INFO - 'config_files': ('talos/mac_config.py',), 05:11:39 INFO - 'default_actions': ('clobber', 05:11:39 INFO - 'read-buildbot-config', 05:11:39 INFO - 'download-and-extract', 05:11:39 INFO - 'populate-webroot', 05:11:39 INFO - 'create-virtualenv', 05:11:39 INFO - 'install', 05:11:39 INFO - 'run-tests'), 05:11:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:11:39 INFO - 'download_minidump_stackwalk': True, 05:11:39 INFO - 'download_symbols': 'ondemand', 05:11:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:11:39 INFO - 'tooltool.py': '/tools/tooltool.py', 05:11:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:11:39 INFO - '/tools/misc-python/virtualenv.py')}, 05:11:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:11:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:11:39 INFO - 'gecko_profile': False, 05:11:39 INFO - 'gecko_profile_interval': 0, 05:11:39 INFO - 'installer_path': 'installer.exe', 05:11:39 INFO - 'log_level': 'info', 05:11:39 INFO - 'log_name': 'talos', 05:11:39 INFO - 'log_to_console': True, 05:11:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:11:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:11:39 INFO - 'opt_config_files': (), 05:11:39 INFO - 'pip_index': False, 05:11:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:11:39 INFO - 'cmd': ('bash', 05:11:39 INFO - '-c', 05:11:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:11:39 INFO - 'enabled': True, 05:11:39 INFO - 'halt_on_failure': False, 05:11:39 INFO - 'name': 'check_screen_resolution'},), 05:11:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:11:39 INFO - 'cmd': ('bash', 05:11:39 INFO - '-c', 05:11:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:11:39 INFO - 'enabled': True, 05:11:39 INFO - 'halt_on_failure': False, 05:11:39 INFO - 'name': 'check_screen_resolution'},), 05:11:39 INFO - 'run_cmd_checks_enabled': True, 05:11:39 INFO - 'suite': 'dromaeojs-e10s', 05:11:39 INFO - 'system_bits': '32', 05:11:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:11:39 INFO - 'title': 't-yosemite-r7-0060', 05:11:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:11:39 INFO - 'use_talos_json': True, 05:11:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:11:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:11:39 INFO - 'work_dir': 'build'} 05:11:39 INFO - [mozharness: 2017-04-06 12:11:39.610199Z] Running clobber step. 05:11:39 INFO - Running pre-action listener: _resource_record_pre_action 05:11:39 INFO - Running main action method: clobber 05:11:39 INFO - rmtree: /builds/slave/test/build 05:11:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:11:42 INFO - Running post-action listener: _resource_record_post_action 05:11:42 INFO - [mozharness: 2017-04-06 12:11:42.264452Z] Finished clobber step (success) 05:11:42 INFO - [mozharness: 2017-04-06 12:11:42.264582Z] Running read-buildbot-config step. 05:11:42 INFO - Running pre-action listener: _resource_record_pre_action 05:11:42 INFO - Running main action method: read_buildbot_config 05:11:42 INFO - Using buildbot properties: 05:11:42 INFO - { 05:11:42 INFO - "project": "", 05:11:42 INFO - "product": "firefox", 05:11:42 INFO - "script_repo_revision": "production", 05:11:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:11:42 INFO - "repository": "", 05:11:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:11:42 INFO - "buildid": "20170406035133", 05:11:42 INFO - "pgo_build": "False", 05:11:42 INFO - "basedir": "/builds/slave/test", 05:11:42 INFO - "buildnumber": 5, 05:11:42 INFO - "slavename": "t-yosemite-r7-0060", 05:11:42 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:11:42 INFO - "platform": "yosemite_r7", 05:11:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:11:42 INFO - "branch": "mozilla-central", 05:11:42 INFO - "repo_path": "mozilla-central", 05:11:42 INFO - "revision": "950612071c4e5cbd61af6c0f66ed0bc40c35e39c", 05:11:42 INFO - "stage_platform": "macosx64", 05:11:42 INFO - "builduid": "7a516864ab5a43b98616afdcb65a46b3", 05:11:42 INFO - "slavebuilddir": "test" 05:11:42 INFO - } 05:11:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:11:42 INFO - Running post-action listener: _resource_record_post_action 05:11:42 INFO - [mozharness: 2017-04-06 12:11:42.267854Z] Finished read-buildbot-config step (success) 05:11:42 INFO - [mozharness: 2017-04-06 12:11:42.267965Z] Running download-and-extract step. 05:11:42 INFO - Running pre-action listener: _resource_record_pre_action 05:11:42 INFO - Running main action method: download_and_extract 05:11:42 INFO - mkdir: /builds/slave/test/build/tests 05:11:42 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')]} 05:11:42 INFO - trying https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:11:42 INFO - Downloading https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/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 05:11:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/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 05:11:42 INFO - Downloaded 1325 bytes. 05:11:42 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:11:42 INFO - Using the following test package requirements: 05:11:42 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:11:42 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:11:42 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:11:42 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:11:42 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:11:42 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:11:42 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:11:42 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:11:42 INFO - u'jsshell-mac.zip'], 05:11:42 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:11:42 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:11:42 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:11:42 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:11:42 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:11:42 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:11:42 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:11:42 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:11:42 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:11:42 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:11:42 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:11:42 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:11:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:11:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:11:42 INFO - Fetch https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:11:44 INFO - Expected file size: 52518651 05:11:44 INFO - Obtained file size: 52518651 05:12:01 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 05:12:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:12:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:12:01 INFO - Fetch https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:12:03 INFO - Expected file size: 52518651 05:12:03 INFO - Obtained file size: 52518651 05:12:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:12:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:12:19 INFO - Fetch https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:12:20 INFO - Expected file size: 14404138 05:12:20 INFO - Obtained file size: 14404138 05:12: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')]} 05:12:20 INFO - trying https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:12:20 INFO - Downloading https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:12:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/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 05:12:22 INFO - Downloaded 70872261 bytes. 05:12:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:12:22 INFO - mkdir: /builds/slave/test/properties 05:12:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:12:22 INFO - Writing to file /builds/slave/test/properties/build_url 05:12:22 INFO - Contents: 05:12:22 INFO - build_url:https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:12:23 INFO - Running post-action listener: _resource_record_post_action 05:12:23 INFO - Running post-action listener: set_extra_try_arguments 05:12:23 INFO - [mozharness: 2017-04-06 12:12:23.247219Z] Finished download-and-extract step (success) 05:12:23 INFO - [mozharness: 2017-04-06 12:12:23.247368Z] Running populate-webroot step. 05:12:23 INFO - Running pre-action listener: _resource_record_pre_action 05:12:23 INFO - Running main action method: populate_webroot 05:12:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:12:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:12:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:12:23 INFO - '--tppagecycles', 05:12:23 INFO - '7'], 05:12:23 INFO - 'tests': ['tsvgm']}}, 05:12:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:12:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:12:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:12:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:12:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:12:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:12:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:12:23 INFO - 'talos_options': ['--disable-e10s'], 05:12:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:12:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:12:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:12:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:12:23 INFO - 'talos_options': ['--disable-e10s'], 05:12:23 INFO - 'tests': ['damp', 'tps']}, 05:12:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:12:23 INFO - 'tests': ['damp', 'tps']}, 05:12:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:12:23 INFO - 'tests': ['dromaeo_dom']}, 05:12:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:12:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:12:23 INFO - 'tests': ['basic_compositor_video']}, 05:12:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:12:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:12:23 INFO - 'tests': ['a11yr', 05:12:23 INFO - 'ts_paint', 05:12:23 INFO - 'tpaint', 05:12:23 INFO - 'sessionrestore', 05:12:23 INFO - 'sessionrestore_no_auto_restore', 05:12:23 INFO - 'tabpaint']}, 05:12:23 INFO - 'other-e10s': {'tests': ['a11yr', 05:12:23 INFO - 'ts_paint', 05:12:23 INFO - 'tpaint', 05:12:23 INFO - 'sessionrestore', 05:12:23 INFO - 'sessionrestore_no_auto_restore', 05:12:23 INFO - 'tabpaint']}, 05:12:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:12:23 INFO - 'ts_paint', 05:12:23 INFO - 'tpaint', 05:12:23 INFO - 'sessionrestore', 05:12:23 INFO - 'sessionrestore_no_auto_restore', 05:12:23 INFO - 'tabpaint']}, 05:12:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:12:23 INFO - 'ts_paint', 05:12:23 INFO - 'tpaint', 05:12:23 INFO - 'sessionrestore', 05:12:23 INFO - 'sessionrestore_no_auto_restore', 05:12:23 INFO - 'tabpaint']}, 05:12:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:12:23 INFO - 'tests': ['a11yr', 05:12:23 INFO - 'ts_paint', 05:12:23 INFO - 'tpaint', 05:12:23 INFO - 'sessionrestore', 05:12:23 INFO - 'sessionrestore_no_auto_restore', 05:12:23 INFO - 'tabpaint']}, 05:12:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:12:23 INFO - 'tests': ['a11yr', 05:12:23 INFO - 'ts_paint', 05:12:23 INFO - 'tpaint', 05:12:23 INFO - 'sessionrestore', 05:12:23 INFO - 'sessionrestore_no_auto_restore', 05:12:23 INFO - 'tabpaint']}, 05:12:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:12:23 INFO - 'tests': ['tsvgx', 05:12:23 INFO - 'tsvgr_opacity', 05:12:23 INFO - 'tart', 05:12:23 INFO - 'tscrollx', 05:12:23 INFO - 'cart', 05:12:23 INFO - 'tsvg_static']}, 05:12:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:12:23 INFO - 'tsvgr_opacity', 05:12:23 INFO - 'tart', 05:12:23 INFO - 'tscrollx', 05:12:23 INFO - 'cart', 05:12:23 INFO - 'tsvg_static']}, 05:12:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:12:23 INFO - 'talos_options': ['--disable-e10s'], 05:12:23 INFO - 'tests': ['tp5o']}, 05:12:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:12:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:12:23 INFO - 'talos_options': ['--disable-e10s', 05:12:23 INFO - '--xperf_path', 05:12:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:12:23 INFO - 'tests': ['tp5n']}, 05:12:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:12:23 INFO - 'talos_options': ['--xperf_path', 05:12:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:12:23 INFO - 'tests': ['tp5n']}}, 05:12:23 INFO - 'talos.zip': {'path': '', 05:12:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:12:23 INFO - Running post-action listener: _resource_record_post_action 05:12:23 INFO - [mozharness: 2017-04-06 12:12:23.265769Z] Finished populate-webroot step (success) 05:12:23 INFO - [mozharness: 2017-04-06 12:12:23.265886Z] Running create-virtualenv step. 05:12:23 INFO - Running pre-action listener: _resource_record_pre_action 05:12:23 INFO - Running main action method: create_virtualenv 05:12:23 INFO - Creating virtualenv /builds/slave/test/build/venv 05:12:23 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:12:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:12:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:12:23 INFO - Using real prefix '/tools/python27' 05:12:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:12:24 INFO - Installing distribute.............................................................................................................................................................................................done. 05:12:26 INFO - Installing pip.................done. 05:12:26 INFO - Return code: 0 05:12:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:12:27 INFO - Reading from file tmpfile_stdout 05:12:27 INFO - Output received: 05:12:27 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:12:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:12:27 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')]} 05:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:27 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')]} 05:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x105156b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105159768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa680c6f990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f34d30>, '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 0x104e5bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f29be8>, '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.aP6adHBFKU/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.12swxwcZKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 05:12:27 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 05:12:27 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 05:12:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP6adHBFKU/Render', 05:12:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:27 INFO - 'HOME': '/Users/cltbld', 05:12:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:27 INFO - 'LOGNAME': 'cltbld', 05:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:27 INFO - 'MOZ_NO_REMOTE': '1', 05:12:27 INFO - 'NO_EM_RESTART': '1', 05:12:27 INFO - 'PAGER': '/bin/cat', 05:12:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:27 INFO - 'PWD': '/builds/slave/test', 05:12:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:27 INFO - 'SHELL': '/bin/bash', 05:12:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.12swxwcZKm/Listeners', 05:12:27 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 05:12:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:27 INFO - 'USER': 'cltbld', 05:12:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:27 INFO - 'XPC_FLAGS': '0x0', 05:12:27 INFO - 'XPC_SERVICE_NAME': '0', 05:12:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:27 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 05:12:27 INFO - Cleaning up... 05:12:27 INFO - Return code: 0 05:12:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:12:27 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')]} 05:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:27 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')]} 05:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x105156b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105159768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa680c6f990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f34d30>, '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 0x104e5bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f29be8>, '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.aP6adHBFKU/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.12swxwcZKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 05:12:27 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 05:12:27 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 05:12:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP6adHBFKU/Render', 05:12:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:27 INFO - 'HOME': '/Users/cltbld', 05:12:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:27 INFO - 'LOGNAME': 'cltbld', 05:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:27 INFO - 'MOZ_NO_REMOTE': '1', 05:12:27 INFO - 'NO_EM_RESTART': '1', 05:12:27 INFO - 'PAGER': '/bin/cat', 05:12:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:27 INFO - 'PWD': '/builds/slave/test', 05:12:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:27 INFO - 'SHELL': '/bin/bash', 05:12:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.12swxwcZKm/Listeners', 05:12:27 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 05:12:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:27 INFO - 'USER': 'cltbld', 05:12:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:27 INFO - 'XPC_FLAGS': '0x0', 05:12:27 INFO - 'XPC_SERVICE_NAME': '0', 05:12:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:27 INFO - Downloading/unpacking psutil>=3.1.1 05:12: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 05:12: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 05:12: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 05:12: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 05:12: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 05:12: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 05:12:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:12:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:12:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:12:31 INFO - Installing collected packages: psutil 05:12:31 INFO - Running setup.py install for psutil 05:12:31 INFO - building 'psutil._psutil_osx' extension 05:12:31 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 05:12:32 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 05:12:32 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 05:12:32 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 05:12:32 INFO - building 'psutil._psutil_posix' extension 05:12:32 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 05:12:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:12:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:12:32 INFO - ^ 05:12:32 INFO - 1 warning generated. 05:12:32 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 05:12:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:12:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:12:32 INFO - Successfully installed psutil 05:12:32 INFO - Cleaning up... 05:12:32 INFO - Return code: 0 05:12:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:12:32 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')]} 05:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:32 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')]} 05:12:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:32 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 0x105156b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105159768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa680c6f990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f34d30>, '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 0x104e5bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f29be8>, '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.aP6adHBFKU/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.12swxwcZKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 05:12:32 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 05:12:32 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 05:12:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP6adHBFKU/Render', 05:12:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:32 INFO - 'HOME': '/Users/cltbld', 05:12:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:32 INFO - 'LOGNAME': 'cltbld', 05:12:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:32 INFO - 'MOZ_NO_REMOTE': '1', 05:12:32 INFO - 'NO_EM_RESTART': '1', 05:12:32 INFO - 'PAGER': '/bin/cat', 05:12:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:32 INFO - 'PWD': '/builds/slave/test', 05:12:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:32 INFO - 'SHELL': '/bin/bash', 05:12:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.12swxwcZKm/Listeners', 05:12:32 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 05:12:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:32 INFO - 'USER': 'cltbld', 05:12:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:32 INFO - 'XPC_FLAGS': '0x0', 05:12:32 INFO - 'XPC_SERVICE_NAME': '0', 05:12:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:12:32 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 05:12:32 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 05:12:32 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 05:12:32 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 05:12:32 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 05:12:32 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 05:12:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:12:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:12:35 INFO - Installing collected packages: mozsystemmonitor 05:12:35 INFO - Running setup.py install for mozsystemmonitor 05:12:35 INFO - Successfully installed mozsystemmonitor 05:12:35 INFO - Cleaning up... 05:12:35 INFO - Return code: 0 05:12:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:12:35 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')]} 05:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:36 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')]} 05:12:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x105156b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105159768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa680c6f990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f34d30>, '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 0x104e5bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f29be8>, '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.aP6adHBFKU/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.12swxwcZKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 05:12:36 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 05:12:36 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 05:12:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP6adHBFKU/Render', 05:12:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:36 INFO - 'HOME': '/Users/cltbld', 05:12:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:36 INFO - 'LOGNAME': 'cltbld', 05:12:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:36 INFO - 'MOZ_NO_REMOTE': '1', 05:12:36 INFO - 'NO_EM_RESTART': '1', 05:12:36 INFO - 'PAGER': '/bin/cat', 05:12:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:36 INFO - 'PWD': '/builds/slave/test', 05:12:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:36 INFO - 'SHELL': '/bin/bash', 05:12:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.12swxwcZKm/Listeners', 05:12:36 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 05:12:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:36 INFO - 'USER': 'cltbld', 05:12:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:36 INFO - 'XPC_FLAGS': '0x0', 05:12:36 INFO - 'XPC_SERVICE_NAME': '0', 05:12:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:36 INFO - Downloading/unpacking jsonschema==2.5.1 05:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:36 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 05:12:36 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 05:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:12:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:39 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 05:12:39 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 05:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:40 INFO - Downloading functools32-3.2.3-2.tar.gz 05:12:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:12:40 INFO - warning: no files found matching '*.txt' 05:12:40 INFO - no previously-included directories found matching 'build' 05:12:40 INFO - no previously-included directories found matching 'dist' 05:12:40 INFO - no previously-included directories found matching '.git*' 05:12:40 INFO - Installing collected packages: jsonschema, functools32 05:12:40 INFO - Running setup.py install for functools32 05:12:40 INFO - warning: no files found matching '*.txt' 05:12:40 INFO - no previously-included directories found matching 'build' 05:12:40 INFO - no previously-included directories found matching 'dist' 05:12:40 INFO - no previously-included directories found matching '.git*' 05:12:40 INFO - Successfully installed jsonschema functools32 05:12:40 INFO - Cleaning up... 05:12:40 INFO - Return code: 0 05:12:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:12:40 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')]} 05:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:40 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')]} 05:12:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:40 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 0x105156b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105159768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa680c6f990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f34d30>, '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 0x104e5bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f29be8>, '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.aP6adHBFKU/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.12swxwcZKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 05:12:40 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 05:12:40 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 05:12:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP6adHBFKU/Render', 05:12:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:40 INFO - 'HOME': '/Users/cltbld', 05:12:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:40 INFO - 'LOGNAME': 'cltbld', 05:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:40 INFO - 'MOZ_NO_REMOTE': '1', 05:12:40 INFO - 'NO_EM_RESTART': '1', 05:12:40 INFO - 'PAGER': '/bin/cat', 05:12:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:40 INFO - 'PWD': '/builds/slave/test', 05:12:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:40 INFO - 'SHELL': '/bin/bash', 05:12:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.12swxwcZKm/Listeners', 05:12:40 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 05:12:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:40 INFO - 'USER': 'cltbld', 05:12:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:40 INFO - 'XPC_FLAGS': '0x0', 05:12:40 INFO - 'XPC_SERVICE_NAME': '0', 05:12:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:12:41 INFO - Cleaning up... 05:12:41 INFO - Return code: 0 05:12:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:12:41 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')]} 05:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:41 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')]} 05:12:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x105156b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105159768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa680c6f990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f34d30>, '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 0x104e5bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f29be8>, '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.aP6adHBFKU/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.12swxwcZKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 05:12:41 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 05:12:41 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 05:12:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP6adHBFKU/Render', 05:12:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:41 INFO - 'HOME': '/Users/cltbld', 05:12:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:41 INFO - 'LOGNAME': 'cltbld', 05:12:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:41 INFO - 'MOZ_NO_REMOTE': '1', 05:12:41 INFO - 'NO_EM_RESTART': '1', 05:12:41 INFO - 'PAGER': '/bin/cat', 05:12:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:41 INFO - 'PWD': '/builds/slave/test', 05:12:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:41 INFO - 'SHELL': '/bin/bash', 05:12:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.12swxwcZKm/Listeners', 05:12:41 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 05:12:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:41 INFO - 'USER': 'cltbld', 05:12:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:41 INFO - 'XPC_FLAGS': '0x0', 05:12:41 INFO - 'XPC_SERVICE_NAME': '0', 05:12:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:41 INFO - Downloading/unpacking blobuploader==1.2.4 05:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:41 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 05:12:41 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 05:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:43 INFO - Downloading blobuploader-1.2.4.tar.gz 05:12:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:12:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:44 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 05:12:44 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 05:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:12:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:44 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 05:12:44 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 05:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:45 INFO - Downloading docopt-0.6.1.tar.gz 05:12:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:12:45 INFO - Installing collected packages: blobuploader, requests, docopt 05:12:45 INFO - Running setup.py install for blobuploader 05:12:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:12:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:12:45 INFO - Running setup.py install for requests 05:12:45 INFO - Running setup.py install for docopt 05:12:46 INFO - Successfully installed blobuploader requests docopt 05:12:46 INFO - Cleaning up... 05:12:46 INFO - Return code: 0 05:12:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12:46 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')]} 05:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:46 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')]} 05:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x105156b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105159768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa680c6f990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f34d30>, '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 0x104e5bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f29be8>, '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.aP6adHBFKU/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.12swxwcZKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 05:12:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 05:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.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 05:12:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP6adHBFKU/Render', 05:12:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:46 INFO - 'HOME': '/Users/cltbld', 05:12:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:46 INFO - 'LOGNAME': 'cltbld', 05:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:46 INFO - 'MOZ_NO_REMOTE': '1', 05:12:46 INFO - 'NO_EM_RESTART': '1', 05:12:46 INFO - 'PAGER': '/bin/cat', 05:12:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:46 INFO - 'PWD': '/builds/slave/test', 05:12:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:46 INFO - 'SHELL': '/bin/bash', 05:12:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.12swxwcZKm/Listeners', 05:12:46 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 05:12:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:46 INFO - 'USER': 'cltbld', 05:12:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:46 INFO - 'XPC_FLAGS': '0x0', 05:12:46 INFO - 'XPC_SERVICE_NAME': '0', 05:12:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:12:46 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-1f4AQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:12:46 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-labfPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:12:46 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-NlLmg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:12:46 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-6ppCPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:12:46 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-TAukap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:12:46 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-BeOYh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:12:46 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-uJgzLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:12:47 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-bvj42R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:12:47 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-ud2XuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:12:47 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-kUFYND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:12:47 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-9yN98a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:12:47 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-FoB5tg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:12:47 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-gpiEt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:12:47 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-bH9iHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:47 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-MH2cMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:12:47 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-RobrTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:12:48 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-gG1BiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:12:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:12:48 INFO - Running setup.py install for manifestparser 05:12:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:12:48 INFO - Running setup.py install for mozcrash 05:12:48 INFO - Running setup.py install for mozdebug 05:12:48 INFO - Running setup.py install for mozdevice 05:12:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:12:48 INFO - Running setup.py install for mozfile 05:12:48 INFO - Running setup.py install for mozhttpd 05:12:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:12:49 INFO - Running setup.py install for mozinfo 05:12:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:12:49 INFO - Running setup.py install for mozInstall 05:12:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:12:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:12:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:12:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:12:49 INFO - Running setup.py install for mozleak 05:12:49 INFO - Running setup.py install for mozlog 05:12:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:12:49 INFO - Running setup.py install for moznetwork 05:12:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:12:49 INFO - Running setup.py install for mozprocess 05:12:49 INFO - Running setup.py install for mozprofile 05:12:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:12:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:12:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:12:50 INFO - Running setup.py install for mozrunner 05:12:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:12:50 INFO - Running setup.py install for mozscreenshot 05:12:50 INFO - Running setup.py install for moztest 05:12:50 INFO - Running setup.py install for mozversion 05:12:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:12:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:12:50 INFO - Cleaning up... 05:12:50 INFO - Return code: 0 05:12:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12:50 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')]} 05:12:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:50 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')]} 05:12:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:50 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 0x105156b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105159768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa680c6f990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f34d30>, '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 0x104e5bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f29be8>, '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.aP6adHBFKU/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.12swxwcZKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 05:12:50 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 05:12:50 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 05:12:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP6adHBFKU/Render', 05:12:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:50 INFO - 'HOME': '/Users/cltbld', 05:12:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:50 INFO - 'LOGNAME': 'cltbld', 05:12:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:50 INFO - 'MOZ_NO_REMOTE': '1', 05:12:50 INFO - 'NO_EM_RESTART': '1', 05:12:50 INFO - 'PAGER': '/bin/cat', 05:12:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:50 INFO - 'PWD': '/builds/slave/test', 05:12:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:50 INFO - 'SHELL': '/bin/bash', 05:12:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.12swxwcZKm/Listeners', 05:12:50 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 05:12:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:50 INFO - 'USER': 'cltbld', 05:12:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:50 INFO - 'XPC_FLAGS': '0x0', 05:12:50 INFO - 'XPC_SERVICE_NAME': '0', 05:12:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:12:50 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-twvJmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:12:51 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-0zBXZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:12:51 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-fTd4p4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:12:51 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-MM_RBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:12:51 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-cl0FS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:12:51 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)) 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:12:51 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-e1qc8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:12:51 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)) 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:12:51 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-i0iABu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:12:51 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)) 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:12:51 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-a3pvpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:12:51 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)) 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:12:51 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-RwuhXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:12:51 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)) 05:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:12:51 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-7XoB7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:12:52 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-xmppAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:12:52 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-XrxTjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:12:52 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-XcmgyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:12:52 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)) 05:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:12:52 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-f68jR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:12:52 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)) 05:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:52 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-jy6aCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:52 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)) 05:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:12:52 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-1H7Z2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:12:52 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)) 05:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:12:52 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-4yrMd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:12:52 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)) 05:12:52 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)) 05:12:52 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)) 05:12:52 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)) 05:12:52 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:12:52 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:12:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:12:52 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 05:12:52 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 05:12:52 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 05:12:52 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 05:12:52 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 05:12:52 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 05:12:55 INFO - Downloading blessings-1.6.tar.gz 05:12:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:12:55 INFO - Installing collected packages: blessings 05:12:55 INFO - Running setup.py install for blessings 05:12:55 INFO - Successfully installed blessings 05:12:55 INFO - Cleaning up... 05:12:55 INFO - Return code: 0 05:12:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:12:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:12:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:12:56 INFO - Reading from file tmpfile_stdout 05:12:56 INFO - Current package versions: 05:12:56 INFO - blessings == 1.6 05:12:56 INFO - blobuploader == 1.2.4 05:12:56 INFO - docopt == 0.6.1 05:12:56 INFO - functools32 == 3.2.3-2 05:12:56 INFO - jsonschema == 2.5.1 05:12:56 INFO - manifestparser == 1.1 05:12:56 INFO - mozInstall == 1.12 05:12:56 INFO - mozcrash == 1.0 05:12:56 INFO - mozdebug == 0.1 05:12:56 INFO - mozdevice == 0.50 05:12:56 INFO - mozfile == 1.2 05:12:56 INFO - mozhttpd == 0.7 05:12:56 INFO - mozinfo == 0.9 05:12:56 INFO - mozleak == 0.1 05:12:56 INFO - mozlog == 3.4 05:12:56 INFO - moznetwork == 0.27 05:12:56 INFO - mozprocess == 0.25 05:12:56 INFO - mozprofile == 0.28 05:12:56 INFO - mozrunner == 6.13 05:12:56 INFO - mozscreenshot == 0.1 05:12:56 INFO - mozsystemmonitor == 0.3 05:12:56 INFO - moztest == 0.8 05:12:56 INFO - mozversion == 1.4 05:12:56 INFO - psutil == 3.1.1 05:12:56 INFO - requests == 1.2.3 05:12:56 INFO - wsgiref == 0.1.2 05:12:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12:56 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')]} 05:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:56 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')]} 05:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:56 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 0x105156b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105159768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa680c6f990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f34d30>, '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 0x104e5bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f29be8>, '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.aP6adHBFKU/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.12swxwcZKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 05:12:56 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 05:12:56 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 05:12:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP6adHBFKU/Render', 05:12:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:56 INFO - 'HOME': '/Users/cltbld', 05:12:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:56 INFO - 'LOGNAME': 'cltbld', 05:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:56 INFO - 'MOZ_NO_REMOTE': '1', 05:12:56 INFO - 'NO_EM_RESTART': '1', 05:12:56 INFO - 'PAGER': '/bin/cat', 05:12:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:56 INFO - 'PWD': '/builds/slave/test', 05:12:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:56 INFO - 'SHELL': '/bin/bash', 05:12:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.12swxwcZKm/Listeners', 05:12:56 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 05:12:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:56 INFO - 'USER': 'cltbld', 05:12:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:56 INFO - 'XPC_FLAGS': '0x0', 05:12:56 INFO - 'XPC_SERVICE_NAME': '0', 05:12:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:12:56 INFO - Cleaning up... 05:12:56 INFO - Return code: 0 05:12:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:12:56 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')]} 05:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:56 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')]} 05:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x105156b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105159768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa680c6f990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f34d30>, '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 0x104e5bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f29be8>, '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.aP6adHBFKU/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.12swxwcZKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 05:12:56 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 05:12:56 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 05:12:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP6adHBFKU/Render', 05:12:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:56 INFO - 'HOME': '/Users/cltbld', 05:12:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:56 INFO - 'LOGNAME': 'cltbld', 05:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:56 INFO - 'MOZ_NO_REMOTE': '1', 05:12:56 INFO - 'NO_EM_RESTART': '1', 05:12:56 INFO - 'PAGER': '/bin/cat', 05:12:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:56 INFO - 'PWD': '/builds/slave/test', 05:12:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:56 INFO - 'SHELL': '/bin/bash', 05:12:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.12swxwcZKm/Listeners', 05:12:56 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 05:12:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:56 INFO - 'USER': 'cltbld', 05:12:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:56 INFO - 'XPC_FLAGS': '0x0', 05:12:56 INFO - 'XPC_SERVICE_NAME': '0', 05:12:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:12:56 INFO - Cleaning up... 05:12:56 INFO - Return code: 0 05:12:56 INFO - Running post-action listener: _resource_record_post_action 05:12:56 INFO - Running post-action listener: _start_resource_monitoring 05:12:56 INFO - Starting resource monitoring. 05:12:56 INFO - [mozharness: 2017-04-06 12:12:56.660214Z] Finished create-virtualenv step (success) 05:12:56 INFO - [mozharness: 2017-04-06 12:12:56.661163Z] Running install step. 05:12:56 INFO - Running pre-action listener: _resource_record_pre_action 05:12:56 INFO - Running main action method: install 05:12:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:12:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:12:56 INFO - Reading from file tmpfile_stdout 05:12:56 INFO - Detecting whether we're running mozinstall >=1.0... 05:12:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:12:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:12:57 INFO - Reading from file tmpfile_stdout 05:12:57 INFO - Output received: 05:12:57 INFO - Usage: mozinstall [options] installer 05:12:57 INFO - Options: 05:12:57 INFO - -h, --help show this help message and exit 05:12:57 INFO - -d DEST, --destination=DEST 05:12:57 INFO - Directory to install application into. [default: 05:12:57 INFO - "/builds/slave/test"] 05:12:57 INFO - --app=APP Application being installed. [default: firefox] 05:12:57 INFO - mkdir: /builds/slave/test/build/application 05:12:57 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'] 05:12:57 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 05:13:17 INFO - Reading from file tmpfile_stdout 05:13:17 INFO - Output received: 05:13:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:13:17 INFO - Running post-action listener: _resource_record_post_action 05:13:17 INFO - [mozharness: 2017-04-06 12:13:17.103974Z] Finished install step (success) 05:13:17 INFO - [mozharness: 2017-04-06 12:13:17.104103Z] Running run-tests step. 05:13:17 INFO - Running pre-action listener: _resource_record_pre_action 05:13:17 INFO - Running main action method: run_tests 05:13:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:13:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:13:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:13:17 INFO - 2017-04-06 05:13:17.118 screenresolution[1625:7521] starting screenresolution argv=screenresolution get 05:13:17 INFO - 2017-04-06 05:13:17.121 screenresolution[1625:7521] Display 0: 1600x1200x32@60 05:13:17 INFO - 2017-04-06 05:13:17.127 screenresolution[1626:7523] starting screenresolution argv=screenresolution list 05:13:17 INFO - Available Modes on Display 0 05:13:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:13:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:13:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:13:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:13:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:13:17 INFO - Intel Iris: 05:13:17 INFO - Chipset Model: Intel Iris 05:13:17 INFO - Type: GPU 05:13:17 INFO - Bus: Built-In 05:13:17 INFO - VRAM (Dynamic, Max): 1536 MB 05:13:17 INFO - Vendor: Intel (0x8086) 05:13:17 INFO - Device ID: 0x0a2e 05:13:17 INFO - Revision ID: 0x0009 05:13:17 INFO - Displays: 05:13:17 INFO - Display: 05:13:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:13:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:13:17 INFO - Display Serial Number: 1600x1200 60 05:13:17 INFO - Main Display: Yes 05:13:17 INFO - Mirror: Off 05:13:17 INFO - Online: Yes 05:13:17 INFO - Rotation: Supported 05:13:17 INFO - Return code: 0 05:13:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:13:17 INFO - Python 2.7.3 05:13:17 INFO - Return code: 0 05:13:17 INFO - grabbing minidump binary from tooltool 05:13: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')]} 05:13:17 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 0x104f34d30>, '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 0x104e5bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f29be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:13:17 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 05:13:17 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 05:13:17 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 05:13:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:13:17 INFO - Return code: 0 05:13:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:13:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:13:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:13:17 INFO - ENV: RUST_BACKTRACE is now 1 05:13:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:13:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:13:17 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/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0060', '--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 05:13:17 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/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0060 --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 05:13:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP6adHBFKU/Render', 05:13:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:17 INFO - 'HOME': '/Users/cltbld', 05:13:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:17 INFO - 'LOGNAME': 'cltbld', 05:13:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:13:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:17 INFO - 'MOZ_NO_REMOTE': '1', 05:13:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:13:17 INFO - 'NO_EM_RESTART': '1', 05:13:17 INFO - 'PAGER': '/bin/cat', 05:13:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:17 INFO - 'PWD': '/builds/slave/test', 05:13:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:13:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:17 INFO - 'RUST_BACKTRACE': '1', 05:13:17 INFO - 'SHELL': '/bin/bash', 05:13:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.12swxwcZKm/Listeners', 05:13:17 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 05:13:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:17 INFO - 'USER': 'cltbld', 05:13:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:17 INFO - 'XPC_FLAGS': '0x0', 05:13:17 INFO - 'XPC_SERVICE_NAME': '0', 05:13:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:17 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/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0060', '--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 05:13:17 INFO - mozversion application_buildid: 20170406035133 05:13:17 INFO - mozversion application_changeset: 950612071c4e5cbd61af6c0f66ed0bc40c35e39c 05:13:17 INFO - mozversion application_display_name: Nightly 05:13:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:13:17 INFO - mozversion application_name: Firefox 05:13:17 INFO - mozversion application_remotingname: firefox 05:13:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:13:17 INFO - mozversion application_vendor: Mozilla 05:13:17 INFO - mozversion application_version: 55.0a1 05:13:17 INFO - mozversion platform_buildid: 20170406035133 05:13:17 INFO - mozversion platform_changeset: 950612071c4e5cbd61af6c0f66ed0bc40c35e39c 05:13:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:13:17 INFO - mozversion platform_version: 55.0a1 05:13:17 INFO - using testdate: 1491480797 05:13:17 INFO - actual date: 1491480797 05:13:17 INFO - starting webserver on 'localhost:49240' 05:13:17 INFO - SUITE-START | Running 2 tests 05:13:17 INFO - TEST-START | dromaeo_css 05:13:17 INFO - operating with platform_type : mac_ 05:13:17 INFO - Initialising browser for dromaeo_css test... 05:13:17 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpl_nRNQ/profile http://localhost:49240/getInfo.html) 05:13:20 INFO - PID 1636 | 2017-04-06 05:13:20.779 plugin-container[1638:7705] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 05:13:20 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:13:20 INFO - PID 1636 | 2017-04-06 05:13:20.782 plugin-container[1638:7705] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 05:13:20 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:13:20 INFO - PID 1636 | 2017-04-06 05:13:20.782 plugin-container[1638:7705] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:13:20 INFO - PID 1636 | __metrics Screen width/height:1600/1200 05:13:20 INFO - PID 1636 | colorDepth:24 05:13:20 INFO - PID 1636 | Browser inner width/height: 1024/689 05:13:20 INFO - PID 1636 | __metrics 05:13:20 ERROR - PID 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 05:13:21 INFO - TEST-INFO | 1636: exit 0 05:13:21 INFO - Browser initialized. 05:13:21 INFO - Running cycle 1/1 for dromaeo_css test... 05:13:22 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpl_nRNQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:13:24 INFO - PID 1639 | 2017-04-06 05:13:24.524 plugin-container[1640:7835] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 05:13:24 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:13:24 INFO - PID 1639 | 2017-04-06 05:13:24.526 plugin-container[1640:7835] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 05:13:24 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:13:24 INFO - PID 1639 | 2017-04-06 05:13:24.526 plugin-container[1640:7835] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:13:34 INFO - PID 1639 | RSS: Main: 233967616 05:13:34 INFO - PID 1639 | 05:16:38 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 05:16:40 INFO - PID 1639 | RSS: Main: 252194816 05:16:40 INFO - PID 1639 | 05:18:22 INFO - PID 1639 | ************************* 05:18:22 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 05:18:22 INFO - PID 1639 | 05:18:22 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:18:22 INFO - PID 1639 | Full stack: 05:18:22 INFO - PID 1639 | ************************* 05:18:22 INFO - PID 1639 | ************************* 05:18:22 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 05:18:22 INFO - PID 1639 | 05:18:22 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:18:22 INFO - PID 1639 | Full stack: 05:18:22 INFO - PID 1639 | ************************* 05:18:22 INFO - PID 1639 | ************************* 05:18:22 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 05:18:22 INFO - PID 1639 | 05:18:22 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:18:22 INFO - PID 1639 | Full stack: 05:18:22 INFO - PID 1639 | ************************* 05:18:22 INFO - PID 1639 | ************************* 05:18:22 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 05:18:22 INFO - PID 1639 | 05:18:22 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:18:22 INFO - PID 1639 | Full stack: 05:18:22 INFO - PID 1639 | ************************* 05:19:42 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 05:19:43 INFO - PID 1639 | RSS: Main: 262832128 05:19:43 INFO - PID 1639 | 05:22:44 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 05:22:45 INFO - PID 1639 | RSS: Main: 274026496 05:22:45 INFO - PID 1639 | 05:25:47 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 05:25:47 INFO - PID 1639 | RSS: Main: 267468800 05:25:47 INFO - PID 1639 | 05:28:50 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 05:28:51 INFO - PID 1639 | RSS: Main: 277790720 05:28:51 INFO - PID 1639 | 05:31:54 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 05:31:54 INFO - PID 1639 | RSS: Main: 271523840 05:31:54 INFO - PID 1639 | 05:31:54 INFO - PID 1639 | __start_tp_report 05:31:54 INFO - PID 1639 | _x_x_mozilla_page_load 05:31:54 INFO - PID 1639 | _x_x_mozilla_page_load_details 05:31:54 INFO - PID 1639 | |i|pagename|runs| 05:31:54 INFO - PID 1639 | |0;dojo.html;2983.433147701935;3608.2061946371796;3620.5655321361432;3634;3640.16276256461;4948.430930443;6191.473724733398;6213.627182369055;6231.408016238456;6242.875142497159;4642.907141857161;6229.906551401733;6230.1900752902175;6250.343713910042;6274.372562743723;4777.976110119462;5479;5506.063969125258;5517;5606.103023516238;5499.532539734128;5569.637973531728;5630.802921897734;5633.549316054727;5666.320041594994;5443.945560544384;5496.065668836268;5545.445455454463;5578.191162281447;5620.241267428885;4867.586255168279;5204.29741984831;5526.668399896019;5527.474889885454;5552.000639884819;4507.2788353863225;5437.564994800407;5461.972690136524;5472.644278121909;5502.972485137549;6033.5173186145;6165.84585385369;6202.224721909762;6243.375662433766;6249.500039996789;6725.899111513331;6783.93216067838;6786.558873673195;6790;6795.082663840368;6839.555428897106;6895.207051189127;6898.241193468714;6898.827529311808;6925.653717314164;5144.71704056273;5380.435054319319;6298.118263443122;6344.587601805868;6347.143135676671;6985.266547012592;7007.299270072952;7031.156261248683;7041.647917604099;7076.150861896605;6337.049442583557;6439.098526206335;6447.806565803034;6549.967250163719;6635.601863888086;6622.966885165543;6733.730650774012;6886.827829304308;6899.8270294049125;6908.5509441886115;6565.770198043046;6798.354156355138;6800.319968003259;6810.693518791666;6823.249442561314;5489.752961116679;6440.194975628046;6640.20317561886;6685.264620891698;6756.6959486822225;5429.239906413105;5452.0731475648445;5459.344878614592;5474.671519708831;5490.423505531941;4829.203181475087;4850.6362022848425;4860.076585448754;4861.732604706775;4881.926771098437;5132.948670513247;6056.121862329937;6221.595596286227;6256.374362563707;6349.873002539924;6791.558548694319;6838.965805170942;6873.243943166248;6878.828029299208;6890.069840571559;6308.116863639094;6345.650989195638;6372.7132279047555;6390.041493775971;6402.295747467775;5883.735231914574;5961.642301461926;5970.34326224115;6091.878162436727;6097.75608975636;6888.104546408935;6990.895136573057;7055.329743674342;7063.01117843492;7084.752033678797;6830.7267709293055;6841.486888483483;6860.451163906975;6861.794146175623;6873;6454.644994525346;7029.507934444539;7083.0791997042115;7105.25394833535;7126.465515086285;5977.133315669291;6078.179445774941;6424.743010279536;6642.701078451578;6685.33146685347;5309.283246761638;6820.965895170492;6830.146231721035;6920.377166055079;6939.722411103499;6345.936540634534;6388.105665206782;6440.710168042356;6467.611943283418;6545.345465453607;6656.866862662817;6662.56693314933;6667.8666426670225;6668.66656667155;6682.164729408824;6277.937220627735;6406.359364063556;6482.837929051811;6512.772052978123;6529;4915.459299476983;5748.827535173951;5806.912896306643;5859.414058594107;6573.3098024706715;5775.5090817281;6473.643949582818;6497.415232629086;6499.512536559682;6505.056766768697 05:31:54 INFO - PID 1639 | |1;ext.html;580.147183640049;797.3939805747632;823.337213543098;1253.918495297805;1273.8407699037614;37083;38231.4265286021;38261.61738382615;38337.61662383372;38375.80812095939;32863.67136328636;36782.08044798881;37797.43303850437;38091.61908380922;38214.04464888379;7932.365410767139;8056.556889371082;8442.62008209632;8668.479891206516;8693.347998900077;8588.828223435528;8625.396222264444;8678.522681252529;8688.782780430476;8716.171963663443;8167.101618821925;8677.783055423602;8706.782330441758;8707.25988290996;8722.08418116095;8649.783755406103;8675.26260267878;8682.392232543725;8705.521196334199;8709.95645021777;8423.241908228289;8454.112318206562;8481.406301558893;8483.363747718946;8485.95757021211;37214.069648259014;37294.81352593247;37353.62646373529;37450.06374840623;37595.43606845899;3474.3746125697367;3515.2442224921765;3516.771409858351;3517.9472307915403;3520.929581408383;30240;30274.243143921358;30298.24254393658;30359.392812143633;30378.392432151453;3082.321889184376;3096.659367469599;3125.078101959927;3129.9687003129907;3140.1364624728153;241316;246545;288409;290456;300239.9999999978;42297.942551435845;50707.49292507101;50906.745466272434;51274;51341;52961.47038529565;53103.734481327345;53177.936441270955;53198.468015320126;53373.7331313341;14319.997600168097;14397.42410303597;14513.782293265609;14514.274286285854;14626.341814618369;11817.468213930393;11823.408829558488;11846.99300559462;11852;11870.821937670942;13793.965452590895;13829.861701383059;13853.930730346485;13935.721285574435;14027.929860350632;5464.371597266286;6008.4592386684535;6034.849128771816;6079.361667024988;6092.45167934888;857.3612658569406;882.3162049411629;946.966856160031;960.5869476125333;1001.7996400719885;22870.856457177073;23354.7664523356;23492.882535587214;23666.526669466515;23671.644925326134;922.7693076730817;942.9811403772023;952.4333021851987;959.390457954363;1076.8492411062457;12044.939775301244;12053.57812476559;12118.575849845389;12192.939035304944;12194.329311887932;383385;384841;384869;385437;386611.0669446635;51786.482135178165;52313.47686523086;52339.691507711854;52364.501727318086;52390.690232744484;1278.213898452445;1505.668752874366;1513.8183417990133;1513.9924300378427;1525.2450037231642;17294.913525432294;21779.673304900756;23459.94430250677;23643.64534532018;23808.52382952366;35570.46644300286;37061.258774823815;37620.247595048495;37680.623193767715;37701.62298376981;327111;327118.36440817645;328541;328746;329560;26541.867290663424;26882.73117268802;31948.041558753273;32541.674583253865;33262.833685831414;39448;39671.2065758689;39957;40635.59364406318;40757.98105047397;27395.726042739316;28985.275368115967;29002.419951600426;29016.12951611455;29271.56092658569;25371.492570148126;28965.42069158563;29042;29136.854315728287;29214.83140674349;33591.66408335885;33611.3277734439;33825.15437114092;33990.15024624404;34490.482642759875;28860.7113928858;29195.70804292015;29426;29702.405951881272;29824.10527684173;17826.643467130845;19277.90361048186;21983.560328793013;23741.406464838517;23768.573885566922 05:31:54 INFO - PID 1639 | |2;jquery.html;425.2006228290813;436.72922787871516;438.6994908487684;454.19380599436005;454.21647657790317;21667.783322166775;21784.89107554462;22508.3247502575;22512.774872251273;22657.54684906301;21651;21763.782362176375;22211.888940555316;22376.776232237673;23150.95820688069;5986.580939334249;6081.331053584113;6290.308066112724;6293.874122517547;6298.527610429213;5294.894102117974;5983.341832398432;6172.197614310134;6185.41238582334;6212.1303017577575;5256.290400795901;5989.161517387569;6070.301915279755;6151.569390142692;6175.197224360827;6066.454019138277;6142.938570614304;6154.569180157391;6189.133521307043;6208;4618.676692631517;5370.865728356823;5455.318085239345;5485.451454854522;5528.336599608033;22601.886990565108;27454.5881811773;27669.723302767117;27823.86088069567;27969.86015069932;1894.1571000904603;1959.7354357161885;2082.781307962657;2099.496120930981;2211.0050477285126;16291.348346066261;17930.5517362067;18090.547736306697;18440.170192341244;18715;2117.3224568138203;2130.6803979403057;2180.7928246816527;2185.2024011235944;2187.7593464719025;122394.38802805929;124434;151053.9999999989;158934;160864;11252.49363778632;11336.149788765875;11635.952764251258;12788.93605531985;12900.419916016834;13428.798568021488;16355.509334719978;16691.99848009123;16761.580960475843;16767.83232167687;8986.640534378552;9188.724338269863;9201.861972070425;9270;9397.812043759086;8834.690785822466;8890.244329231959;9007.63969441228;9023.368364214442;9055.818883622422;7917.485363451357;7982.441229113906;8022.598870056474;8053.59732013397;8079.959600201962;2101.3905967269625;2183.192218879025;2193.6599827026835;2195.7145571075657;2201.5706937147424;856.2507805670039;886.4946980221212;903.4714691905177;928.9256859451227;931.84624536952;17925.820741792675;21098;21318;21466.60309134993;21502.78497215008;951.6241084771615;954.9161701468882;983.4185263682832;988.3871999360299;998.7153661206667;10541.262111652264;10689.89310106889;10737.087347575387;10832.512536935857;10842.566297348176;707829;720284;723859;727353;738779;155977.22011389871;157675;160635.39364606203;164563.17718411572;170873;1163.9010684091777;1219.9573014944435;1263.9178453400684;1266.1263728027632;1268.536984000833;19302.613947720685;20679.379618611467;21453.14187432485;21722.782772172075;22007.009684564553;60196.09705854328;64201.71596567949;64498;64631;65599.34400656124;73988;86124;87430;87640.5617971906;88045;8818.470891746516;8863.556822158736;8907.910920890708;8996.685116020908;9396.295277854051;13975.93012034974;14081.929590351983;14336;14503.274836258355;14553.85446145525;10269.921658226078;11628.94185529067;11895.76208475843;12026.398680066137;12140.33228172459;11308.886911130783;11329.583802024747;11407.429628518375;11474.827877582009;11566.65300041;12203.389830508262;12469.127161098999;12508.186967847061;12531.812022819482;12556.87443125557;8773.473591584525;8983.595738191927;9100.863487047567;9155.954220228856;9224.584893679666;9651.517424128906;10599.62901298451;11903.345316007357;12246.938765306117;12323.75352492963 05:31:54 INFO - PID 1639 | |3;mootools.html;1201.4413297816513;1438.7266419380323;1442.9278536073193;1445.140141615739;1453.7092581483703;11477.885221147786;11852.585159519396;11862.051035917139;11886;12082.758344833099;11779.882201178007;11795.941020294908;11848.111391645618;11995.04039676826;12034.33811140387;2117.502386939072;2499.3376755159807;2504.2987963370288;2513.949721005579;2517.6097704855683;2399.9880000599974;2459.409741661997;2501.024600405843;2501.836645959628;2505.3360859372283;2451.6935383077116;2456.1280745335416;2468.1731619907264;2491.9875400622973;2499.4251322195905;2436.293474892279;2439.609662454008;2444.2422848916717;2451.6077427611585;2505.1482495951464;2380.2859142257425;2422.467057247403;2435.7198922124007;2436.6954130733657;2488.32815139913;11736.413179340998;11802;11806.99640530556;11821.054315654726;11829.645110646694;6449.258335291454;6673.699683514205;6718.126643536358;6734.393904548565;6793.218779840332;3700.833462494167;3910.1397692507603;4199.118185181115;4209.5790420957965;4253.298680527782;1236.3756303066893;1241.8447694038246;1273.6752128207247;1289.9484020639163;1294.6633875192517;14959.626009349744;15422.768658470244;16530.00819950807;16654.75017874733;16677.583060423585;4723.740194289278;5520.475554822286;5678.630888992285;5687.829365119053;5720.513756330675;12337.50649973991;12382.56661016878;12420.62738117858;12426.192297500633;12428.192167509083;5194.558462530652;6327.272363678144;6557.114789503451;6560.901586476207;6569.37590928861;6108.755649774048;6123.510119190454;6234.719437625317;6239;6242.875142497124;4055.330870406409;4443.777811109431;4454.688171828006;4467.173572889026;4530.682852200314;1110.7278716714457;1232.1744431231098;1295.8315418995471;1298.7142828577698;1316.4470922212693;630.621627023782;681.5433659448181;760.2853317881174;766.7354762606892;789.0570767932265;5209.322788037531;5931.110333450017;5984.611000284967;6198.473129783929;6229.470495007975;541.0963690636646;543.071347994933;548.4186762032259;572.0960881806735;574.6465923457043;3472.9060345990933;3589.940967414619;3832.674222691047;4076.205139997672;4205.137946720914;14584;15151.621209469851;15275.083494990336;15311.387544498095;15314.846851531565;15132.470363537223;15157.166355850533;15179.316930738143;15194.164320962233;15231.695366092616;10548.683539493828;10966.561337546409;11014.614488493025;11151.163662725328;11178.776424471465;6408.58344207625;6529.444997175293;6581.07864898905;6602;6603.702833372606;13140.934295328461;13385.063045587105;13756.931215343859;13822.447102116204;13858.722825543231;12644.557440489538;12858.549950751678;14157.292135393394;14163.079399838978;14409;6833.624150671761;7264.891026634499;8693.956530217309;8764.474131552128;8809.471431714117;10926;10935.070519005974;11004.779904402027;11035.779284414106;11041.834372484258;7701.6534255957495;9151.267898568232;9331.673391431268;9361.4383137012;9375.296852735935;9077.046910074347;9186.40288381253;9205.585748641193;9276.814463710552;9380.530973451163;8035.276825085771;8104.27061564462;8154.306883914696;8169.754907352789;8201.79495512617;6007.579469437097;6164.044573091178;6180.412860778219;6193.659348735682;6313.210848643919;5973.522118230618;6170.352113028068;6238.282597501346;6258.812235632939;6312.526560508072 05:31:54 INFO - PID 1639 | |4;prototype.html;312.6451477572954;355.8007515791156;373.79628102684035;388.36114591497005;400.5113761211323;6186.772761805746;6346.7461301548;6397.520185986053;6435.51733619978;6480.449161821245;6112.174856394386;6265.867066466767;6279.775443788463;6338;6364.377083843613;4773.618110551165;4778.9044219115685;4795.136875362451;4797.472278049412;4811.807527698906;4346.217680817452;4444.97777511112;4653.674242803005;4755.595774359183;4788.5450882166315;3995.840166393341;4436.622887054604;4551.772411379435;4552.521985191557;4729.69256998294;4535.614472769818;4545.977270113662;4556.270996640522;4574.61115805157;4586.54134586542;3909.6346710240177;3935.940960885589;3937.7834219117935;3937.98031009846;3953.960460395388;5966.643175945521;6184.350643182491;6220.253569571635;6243.906341404882;6244.3755624437645;5015.699058056528;5437.755301011464;5439.782408703647;5451.046066938309;5456.536194423479;5847.415258474119;6691.397774200345;6757.425618822406;6771.932280677229;6783.3216678332265;4207.200631879933;4405.691601587891;4438.515765124362;4444.777761111932;4460.375547423363;5927.37762534932;6966.457243106281;7159.57042577447;7176.7846964591145;7181.210066892638;4400;4408.232935712491;4486.663500237495;4495.280755079171;4497.2129877271345;4515.819367225275;4592.494825569184;4594.333821595917;4599.5400459953735;4607.792649330788;4505.6846020778885;4689.934870340025;5335.679859208382;5408.242846001563;5442.673439593637;5366;5966.76132954677;6001.278568003776;6702.39678428934;6800.183977922582;1535.9648465782882;1557.8082766683497;1562.3828587707792;1562.874970002397;1565.6633823727952;984.7193549838405;1011.6256984915628;1029.0995379043336;1035.1098055672117;1040.9687709368732;6390.584612000205;6396.424321810966;6415.518836087312;6433.871322573522;6487.124238227779;6676.86646267072;7025.859482810315;7027.329614746363;7044.647767611701;7069.964650176819;6304.338044505353;6314.421394651337;6398.520110991694;6398.632505972727;6404.455621272244;3663.578688450862;3669.119411341259;3672.320620685182;3688.428293614494;3730.6828919542145;6171.259448866164;6891.517593768388;7123.9314102883945;7135;7272.963635181896;6686.0276218515655;6780.694868730919;7245.456590755714;7257.165425976081;7257.786375155628;5652.608434939086;5690.516306113861;5796.681182534917;6353.999999999907;7254.92745072553;5398.433164517826;5889.969255380377;6661.634364955269;7076.540024898365;7095.432365410651;5696.914546281892;5704.828855134353;6162.537809664383;6894.8621027580175;6976.7558135465015;6221.880061588958;6677.165354330708;7029.718811247493;7284.490085694162;7285.417166626551;4380.4305440292555;4443.400140980926;4505.256632655575;4521.886952826206;4522.841700540465;5444.366689993066;6629.867402652017;6769.68285540072;6780.762673306411;7020.368166865007;6678.565893216925;6816.522843400914;6826.590404575742;6832.847652989292;6848.623325716934;6248.9062664059165;6498.252700939263;6800.693968771318;6818.965905170641;6822.761203357859;4957.702537847741;5784.248047753765;6427.55007149495;6637.6681165940545;6672.5329226953645;5269.71016594091;6823.55646882951;6848.8972665409065;6874.900015997417;6890.8621827562165;5224.843254702365;5783.566232532493;6399.8720025598295;6412.813629478562;6709.530332876652 05:31:54 INFO - PID 1639 | |5;yui.html;782.4249176855011;815.2784785464862;827.664795757718;841.1966571923813;843.2747836860301;16250.593735156626;16485.752713709306;16536.7222050294;17057.914710426463;17096.059716715576;15193.92403037986;15540.378384864593;15606.063636181809;15844;15847;948.293521326611;964.8456247000481;968.3318510227955;968.6851773173712;970.7427531704104;884.792073862645;946.8485042393219;980.3137803537517;987.244757760314;993.6720882108887;1029.382370577655;1037.5227395398126;1045.994770026149;1046.9162467002623;1047.958081676736;1028.3521381529627;1034.9223808214338;1038.8078205532001;1042.2860340666668;1042.598599539183;759.5784339691469;761.3832795435715;766.8811334243201;771.1170709537604;772.0658003815334;13156;14096.365663545164;16709.16454177286;16731.6653666926;17453.12734363277;14892.180930048842;15018;15126.924365378103;15157.242137893172;15743.763843542356;13356;13426.932865335708;13677.794833077513;13686.31568421575;13794.379252933642;3351.7151042161445;3360.663933606644;3363.394588973984;3394.0666316763086;3396.3886500429912;118778;120091.79908200893;121869;122100;122371;7911.28798408146;10511.421871796972;11617.672769050278;11722.648320550397;12169.574064907685;24075;24329.27012189637;24433.51132977366;24588;24612;8016.799580010546;8059.798505037421;8072.233137851895;8361.749147525585;8468.703595374256;3537.557805274341;4120.62914337711;4165.75005499671;4176.102138040336;4185.100203456211;4119.6910231732145;4203.600657937491;4215.78921053946;4231;4232.090100628318;906.2795077913075;934.6027938126269;956.8086382723344;958.5494817435793;959.1128206409044;595.5979713693326;618.8267285160163;625.524601303013;631.1164369882201;655.429776094791;10086.844004279961;10182.23633227496;10262.897371026344;10391.428471434143;10567.032009152468;561.6517758989453;605.5246631394423;608.8751805879873;613.5918068033873;637.8998069382795;4798.73701769367;4800.711957282504;4819.469858315582;4846;4850.903293841592;79429.60285198652;98127;102426;102549;104183;76464;76553;87888;89141.66287505563;91799.5410022959;59499.70250148721;70744;70889;74944.62527687436;75471.24528754751;9796.9020309796;9939.453330066626;10158.847617285599;10209.591616335263;10693.786124277316;38283.425748613234;38994.80502597469;39770.80114599408;40254;40917.56788512388;44305.778471107435;45617.31574026326;51399.97200056053;51973;52213;14159.292035398395;14181;14294.571162865132;14491.56566287642;14501.637459063608;14857.628559285671;15031.924840375728;15093.255393456284;15306.540803775904;15842.287097080429;13868.167909925436;13904.175543459021;14018.088824226392;14134.646633834236;15119.697606048037;14012.299385030912;14060.367283472473;14133.679428942327;14143;14562.635934101732;12087.576934807239;13935.721285574435;14229.857701423267;14284.510407526666;14711.852881471048;9865.210783137189;10176.287659863738;10250.538725757508;10619.893801061891;10798.67603971882;10493.632722854663;11076.560047194136;11280.887191127984;11383.772324553629;11449.370284634091 05:31:54 INFO - PID 1639 | __end_tp_report 05:31:54 INFO - PID 1639 | __start_cc_report 05:31:54 INFO - PID 1639 | _x_x_mozilla_cycle_collect,421 05:31:54 INFO - PID 1639 | __end_cc_report 05:31:54 INFO - PID 1639 | __startTimestamp1491481914645__endTimestamp 05:31:54 INFO - PID 1639 | 05:31:54 INFO - PID 1639 | ------- Summary: start ------- 05:31:54 INFO - PID 1639 | Number of tests: 6 05:31:54 INFO - PID 1639 | 05:31:54 INFO - PID 1639 | [#0] dojo.html Cycles:165 Average:6143.06 Median:6347.14 stddev:795.70 (12.5%) stddev-sans-first:758.55 05:31:54 INFO - PID 1639 | Values: 2983.4 3608.2 3620.6 3634.0 3640.2 4948.4 6191.5 6213.6 6231.4 6242.9 4642.9 6229.9 6230.2 6250.3 6274.4 4778.0 5479.0 5506.1 5517.0 5606.1 5499.5 5569.6 5630.8 5633.5 5666.3 5443.9 5496.1 5545.4 5578.2 5620.2 4867.6 5204.3 5526.7 5527.5 5552.0 4507.3 5437.6 5462.0 5472.6 5503.0 6033.5 6165.8 6202.2 6243.4 6249.5 6725.9 6783.9 6786.6 6790.0 6795.1 6839.6 6895.2 6898.2 6898.8 6925.7 5144.7 5380.4 6298.1 6344.6 6347.1 6985.3 7007.3 7031.2 7041.6 7076.2 6337.0 6439.1 6447.8 6550.0 6635.6 6623.0 6733.7 6886.8 6899.8 6908.6 6565.8 6798.4 6800.3 6810.7 6823.2 5489.8 6440.2 6640.2 6685.3 6756.7 5429.2 5452.1 5459.3 5474.7 5490.4 4829.2 4850.6 4860.1 4861.7 4881.9 5132.9 6056.1 6221.6 6256.4 6349.9 6791.6 6839.0 6873.2 6878.8 6890.1 6308.1 6345.7 6372.7 6390.0 6402.3 5883.7 5961.6 5970.3 6091.9 6097.8 6888.1 6990.9 7055.3 7063.0 7084.8 6830.7 6841.5 6860.5 6861.8 6873.0 6454.6 7029.5 7083.1 7105.3 7126.5 5977.1 6078.2 6424.7 6642.7 6685.3 5309.3 6821.0 6830.1 6920.4 6939.7 6345.9 6388.1 6440.7 6467.6 6545.3 6656.9 6662.6 6667.9 6668.7 6682.2 6277.9 6406.4 6482.8 6512.8 6529.0 4915.5 5748.8 5806.9 5859.4 6573.3 5775.5 6473.6 6497.4 6499.5 6505.1 05:31:54 INFO - PID 1639 | 05:31:54 INFO - PID 1639 | [#1] ext.html Cycles:180 Average:46954.89 Median:3494.81 stddev:87681.76 (2508.9%) stddev-sans-first:87858.60 05:31:54 INFO - PID 1639 | Values: 580.1 797.4 823.3 1253.9 1273.8 37083.0 38231.4 38261.6 38337.6 38375.8 32863.7 36782.1 37797.4 38091.6 38214.0 7932.4 8056.6 8442.6 8668.5 8693.3 8588.8 8625.4 8678.5 8688.8 8716.2 8167.1 8677.8 8706.8 8707.3 8722.1 8649.8 8675.3 8682.4 8705.5 8710.0 8423.2 8454.1 8481.4 8483.4 8486.0 37214.1 37294.8 37353.6 37450.1 37595.4 3474.4 3515.2 3516.8 3517.9 3520.9 30240.0 30274.2 30298.2 30359.4 30378.4 3082.3 3096.7 3125.1 3130.0 3140.1 241316.0 246545.0 288409.0 290456.0 300240.0 42297.9 50707.5 50906.7 51274.0 51341.0 52961.5 53103.7 53177.9 53198.5 53373.7 14320.0 14397.4 14513.8 14514.3 14626.3 11817.5 11823.4 11847.0 11852.0 11870.8 13794.0 13829.9 13853.9 13935.7 14027.9 5464.4 6008.5 6034.8 6079.4 6092.5 857.4 882.3 947.0 960.6 1001.8 22870.9 23354.8 23492.9 23666.5 23671.6 922.8 943.0 952.4 959.4 1076.8 12044.9 12053.6 12118.6 12192.9 12194.3 383385.0 384841.0 384869.0 385437.0 386611.1 51786.5 52313.5 52339.7 52364.5 52390.7 1278.2 1505.7 1513.8 1514.0 1525.2 17294.9 21779.7 23459.9 23643.6 23808.5 35570.5 37061.3 37620.2 37680.6 37701.6 327111.0 327118.4 328541.0 328746.0 329560.0 26541.9 26882.7 31948.0 32541.7 33262.8 39448.0 39671.2 39957.0 40635.6 40758.0 27395.7 28985.3 29002.4 29016.1 29271.6 25371.5 28965.4 29042.0 29136.9 29214.8 33591.7 33611.3 33825.2 33990.2 34490.5 28860.7 29195.7 29426.0 29702.4 29824.1 17826.6 19277.9 21983.6 23741.4 23768.6 05:31:54 INFO - PID 1639 | 05:31:54 INFO - PID 1639 | [#2] jquery.html Cycles:180 Average:41439.80 Median:22510.55 stddev:121106.49 (538.0%) stddev-sans-first:121407.07 05:31:54 INFO - PID 1639 | Values: 425.2 436.7 438.7 454.2 454.2 21667.8 21784.9 22508.3 22512.8 22657.5 21651.0 21763.8 22211.9 22376.8 23151.0 5986.6 6081.3 6290.3 6293.9 6298.5 5294.9 5983.3 6172.2 6185.4 6212.1 5256.3 5989.2 6070.3 6151.6 6175.2 6066.5 6142.9 6154.6 6189.1 6208.0 4618.7 5370.9 5455.3 5485.5 5528.3 22601.9 27454.6 27669.7 27823.9 27969.9 1894.2 1959.7 2082.8 2099.5 2211.0 16291.3 17930.6 18090.5 18440.2 18715.0 2117.3 2130.7 2180.8 2185.2 2187.8 122394.4 124434.0 151054.0 158934.0 160864.0 11252.5 11336.1 11636.0 12788.9 12900.4 13428.8 16355.5 16692.0 16761.6 16767.8 8986.6 9188.7 9201.9 9270.0 9397.8 8834.7 8890.2 9007.6 9023.4 9055.8 7917.5 7982.4 8022.6 8053.6 8080.0 2101.4 2183.2 2193.7 2195.7 2201.6 856.3 886.5 903.5 928.9 931.8 17925.8 21098.0 21318.0 21466.6 21502.8 951.6 954.9 983.4 988.4 998.7 10541.3 10689.9 10737.1 10832.5 10842.6 707829.0 720284.0 723859.0 727353.0 738779.0 155977.2 157675.0 160635.4 164563.2 170873.0 1163.9 1220.0 1263.9 1266.1 1268.5 19302.6 20679.4 21453.1 21722.8 22007.0 60196.1 64201.7 64498.0 64631.0 65599.3 73988.0 86124.0 87430.0 87640.6 88045.0 8818.5 8863.6 8907.9 8996.7 9396.3 13975.9 14081.9 14336.0 14503.3 14553.9 10269.9 11628.9 11895.8 12026.4 12140.3 11308.9 11329.6 11407.4 11474.8 11566.7 12203.4 12469.1 12508.2 12531.8 12556.9 8773.5 8983.6 9100.9 9156.0 9224.6 9651.5 10599.6 11903.3 12246.9 12323.8 05:31:54 INFO - PID 1639 | 05:31:54 INFO - PID 1639 | [#3] mootools.html Cycles:180 Average:7139.55 Median:2504.72 stddev:4610.62 (184.1%) stddev-sans-first:4601.96 05:31:54 INFO - PID 1639 | Values: 1201.4 1438.7 1442.9 1445.1 1453.7 11477.9 11852.6 11862.1 11886.0 12082.8 11779.9 11795.9 11848.1 11995.0 12034.3 2117.5 2499.3 2504.3 2513.9 2517.6 2400.0 2459.4 2501.0 2501.8 2505.3 2451.7 2456.1 2468.2 2492.0 2499.4 2436.3 2439.6 2444.2 2451.6 2505.1 2380.3 2422.5 2435.7 2436.7 2488.3 11736.4 11802.0 11807.0 11821.1 11829.6 6449.3 6673.7 6718.1 6734.4 6793.2 3700.8 3910.1 4199.1 4209.6 4253.3 1236.4 1241.8 1273.7 1289.9 1294.7 14959.6 15422.8 16530.0 16654.8 16677.6 4723.7 5520.5 5678.6 5687.8 5720.5 12337.5 12382.6 12420.6 12426.2 12428.2 5194.6 6327.3 6557.1 6560.9 6569.4 6108.8 6123.5 6234.7 6239.0 6242.9 4055.3 4443.8 4454.7 4467.2 4530.7 1110.7 1232.2 1295.8 1298.7 1316.4 630.6 681.5 760.3 766.7 789.1 5209.3 5931.1 5984.6 6198.5 6229.5 541.1 543.1 548.4 572.1 574.6 3472.9 3589.9 3832.7 4076.2 4205.1 14584.0 15151.6 15275.1 15311.4 15314.8 15132.5 15157.2 15179.3 15194.2 15231.7 10548.7 10966.6 11014.6 11151.2 11178.8 6408.6 6529.4 6581.1 6602.0 6603.7 13140.9 13385.1 13756.9 13822.4 13858.7 12644.6 12858.5 14157.3 14163.1 14409.0 6833.6 7264.9 8694.0 8764.5 8809.5 10926.0 10935.1 11004.8 11035.8 11041.8 7701.7 9151.3 9331.7 9361.4 9375.3 9077.0 9186.4 9205.6 9276.8 9380.5 8035.3 8104.3 8154.3 8169.8 8201.8 6007.6 6164.0 6180.4 6193.7 6313.2 5973.5 6170.4 6238.3 6258.8 6312.5 05:31:54 INFO - PID 1639 | 05:31:54 INFO - PID 1639 | [#4] prototype.html Cycles:180 Average:5387.09 Median:5966.70 stddev:1689.02 (28.3%) stddev-sans-first:1650.26 05:31:54 INFO - PID 1639 | Values: 312.6 355.8 373.8 388.4 400.5 6186.8 6346.7 6397.5 6435.5 6480.4 6112.2 6265.9 6279.8 6338.0 6364.4 4773.6 4778.9 4795.1 4797.5 4811.8 4346.2 4445.0 4653.7 4755.6 4788.5 3995.8 4436.6 4551.8 4552.5 4729.7 4535.6 4546.0 4556.3 4574.6 4586.5 3909.6 3935.9 3937.8 3938.0 3954.0 5966.6 6184.4 6220.3 6243.9 6244.4 5015.7 5437.8 5439.8 5451.0 5456.5 5847.4 6691.4 6757.4 6771.9 6783.3 4207.2 4405.7 4438.5 4444.8 4460.4 5927.4 6966.5 7159.6 7176.8 7181.2 4400.0 4408.2 4486.7 4495.3 4497.2 4515.8 4592.5 4594.3 4599.5 4607.8 4505.7 4689.9 5335.7 5408.2 5442.7 5366.0 5966.8 6001.3 6702.4 6800.2 1536.0 1557.8 1562.4 1562.9 1565.7 984.7 1011.6 1029.1 1035.1 1041.0 6390.6 6396.4 6415.5 6433.9 6487.1 6676.9 7025.9 7027.3 7044.6 7070.0 6304.3 6314.4 6398.5 6398.6 6404.5 3663.6 3669.1 3672.3 3688.4 3730.7 6171.3 6891.5 7123.9 7135.0 7273.0 6686.0 6780.7 7245.5 7257.2 7257.8 5652.6 5690.5 5796.7 6354.0 7254.9 5398.4 5890.0 6661.6 7076.5 7095.4 5696.9 5704.8 6162.5 6894.9 6976.8 6221.9 6677.2 7029.7 7284.5 7285.4 4380.4 4443.4 4505.3 4521.9 4522.8 5444.4 6629.9 6769.7 6780.8 7020.4 6678.6 6816.5 6826.6 6832.8 6848.6 6248.9 6498.3 6800.7 6819.0 6822.8 4957.7 5784.2 6427.6 6637.7 6672.5 5269.7 6823.6 6848.9 6874.9 6890.9 5224.8 5783.6 6399.9 6412.8 6709.5 05:31:54 INFO - PID 1639 | 05:31:54 INFO - PID 1639 | [#5] yui.html Cycles:180 Average:19995.12 Median:24381.39 stddev:28690.39 (117.7%) stddev-sans-first:28734.61 05:31:54 INFO - PID 1639 | Values: 782.4 815.3 827.7 841.2 843.3 16250.6 16485.8 16536.7 17057.9 17096.1 15193.9 15540.4 15606.1 15844.0 15847.0 948.3 964.8 968.3 968.7 970.7 884.8 946.8 980.3 987.2 993.7 1029.4 1037.5 1046.0 1046.9 1048.0 1028.4 1034.9 1038.8 1042.3 1042.6 759.6 761.4 766.9 771.1 772.1 13156.0 14096.4 16709.2 16731.7 17453.1 14892.2 15018.0 15126.9 15157.2 15743.8 13356.0 13426.9 13677.8 13686.3 13794.4 3351.7 3360.7 3363.4 3394.1 3396.4 118778.0 120091.8 121869.0 122100.0 122371.0 7911.3 10511.4 11617.7 11722.6 12169.6 24075.0 24329.3 24433.5 24588.0 24612.0 8016.8 8059.8 8072.2 8361.7 8468.7 3537.6 4120.6 4165.8 4176.1 4185.1 4119.7 4203.6 4215.8 4231.0 4232.1 906.3 934.6 956.8 958.5 959.1 595.6 618.8 625.5 631.1 655.4 10086.8 10182.2 10262.9 10391.4 10567.0 561.7 605.5 608.9 613.6 637.9 4798.7 4800.7 4819.5 4846.0 4850.9 79429.6 98127.0 102426.0 102549.0 104183.0 76464.0 76553.0 87888.0 89141.7 91799.5 59499.7 70744.0 70889.0 74944.6 75471.2 9796.9 9939.5 10158.8 10209.6 10693.8 38283.4 38994.8 39770.8 40254.0 40917.6 44305.8 45617.3 51400.0 51973.0 52213.0 14159.3 14181.0 14294.6 14491.6 14501.6 14857.6 15031.9 15093.3 15306.5 15842.3 13868.2 13904.2 14018.1 14134.6 15119.7 14012.3 14060.4 14133.7 14143.0 14562.6 12087.6 13935.7 14229.9 14284.5 14711.9 9865.2 10176.3 10250.5 10619.9 10798.7 10493.6 11076.6 11280.9 11383.8 11449.4 05:31:54 INFO - PID 1639 | -------- Summary: end -------- 05:31:54 INFO - PID 1639 | 05:31:55 INFO - TEST-INFO | 1639: exit 0 05:31:55 INFO - TEST-OK | dromaeo_css | took 1117603ms 05:31:55 INFO - TEST-START | kraken 05:31:55 INFO - operating with platform_type : mac_ 05:31:55 INFO - Initialising browser for kraken test... 05:31:55 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpe6V75v/profile http://localhost:49240/getInfo.html) 05:31:58 INFO - PID 1653 | 2017-04-06 05:31:58.197 plugin-container[1654:10293] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 05:31:58 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:31:58 INFO - PID 1653 | 2017-04-06 05:31:58.200 plugin-container[1654:10293] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 05:31:58 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:31:58 INFO - PID 1653 | 2017-04-06 05:31:58.200 plugin-container[1654:10293] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:31:58 INFO - PID 1653 | __metrics Screen width/height:1600/1200 05:31:58 INFO - PID 1653 | colorDepth:24 05:31:58 INFO - PID 1653 | Browser inner width/height: 1024/689 05:31:58 INFO - PID 1653 | __metrics 05:31:58 ERROR - PID 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 05:31:59 INFO - TEST-INFO | 1653: exit 0 05:31:59 INFO - Browser initialized. 05:31:59 INFO - Running cycle 1/1 for kraken test... 05:31:59 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpe6V75v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:02 INFO - PID 1655 | 2017-04-06 05:32:02.169 plugin-container[1656:10425] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 05:32:02 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:32:02 INFO - PID 1655 | 2017-04-06 05:32:02.171 plugin-container[1656:10425] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 05:32:02 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:32:02 INFO - PID 1655 | 2017-04-06 05:32:02.171 plugin-container[1656:10425] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:32:11 INFO - PID 1655 | RSS: Main: 234393600 05:32:11 INFO - PID 1655 | 05:32:14 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 05:32:15 INFO - PID 1655 | RSS: Main: 248696832 05:32:15 INFO - PID 1655 | 05:32:18 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 05:32:18 INFO - PID 1655 | RSS: Main: 257359872 05:32:18 INFO - PID 1655 | 05:32:22 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 05:32:23 INFO - PID 1655 | RSS: Main: 259317760 05:32:23 INFO - PID 1655 | 05:32:25 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 05:32:25 INFO - PID 1655 | RSS: Main: 260517888 05:32:25 INFO - PID 1655 | 05:32:27 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:32:28 INFO - PID 1655 | RSS: Main: 260837376 05:32:28 INFO - PID 1655 | 05:32:31 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 05:32:32 INFO - PID 1655 | RSS: Main: 265023488 05:32:32 INFO - PID 1655 | 05:32:36 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 05:32:36 INFO - PID 1655 | RSS: Main: 265695232 05:32:36 INFO - PID 1655 | 05:32:40 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 05:32:40 INFO - PID 1655 | RSS: Main: 265609216 05:32:40 INFO - PID 1655 | 05:32:42 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:32:43 INFO - PID 1655 | RSS: Main: 266469376 05:32:43 INFO - PID 1655 | 05:32:45 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:32:45 INFO - PID 1655 | RSS: Main: 266731520 05:32:45 INFO - PID 1655 | 05:32:47 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:32:48 INFO - PID 1655 | RSS: Main: 266772480 05:32:48 INFO - PID 1655 | 05:32:51 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:32:52 INFO - PID 1655 | RSS: Main: 267182080 05:32:52 INFO - PID 1655 | 05:32:55 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:32:56 INFO - PID 1655 | RSS: Main: 264777728 05:32:56 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 05:32:58 INFO - PID 1655 | RSS: Main: 268120064 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | __start_tp_report 05:32:58 INFO - PID 1655 | _x_x_mozilla_page_load 05:32:58 INFO - PID 1655 | _x_x_mozilla_page_load_details 05:32:58 INFO - PID 1655 | |i|pagename|runs| 05:32:58 INFO - PID 1655 | |0;ai-astar;109;122;121;124;123;122;122;122;121;125 05:32:58 INFO - PID 1655 | |1;audio-beat-detection;155;116;117;154;116;124;105;113;119;125 05:32:58 INFO - PID 1655 | |2;audio-dft;215;218;233;258;306;259;215;216;215;214 05:32:58 INFO - PID 1655 | |3;audio-fft;88;59;61;89;50;66;81;87;103;87 05:32:58 INFO - PID 1655 | |4;audio-oscillator;86;82;120;107;106;74;92;100;105;94 05:32:58 INFO - PID 1655 | |5;imaging-gaussian-blur;95;130;138;132;90;132;87;139;105;91 05:32:58 INFO - PID 1655 | |6;imaging-darkroom;131;143;109;162;127;101;101;103;151;100 05:32:58 INFO - PID 1655 | |7;imaging-desaturate;101;101;144;161;125;165;103;103;103;103 05:32:58 INFO - PID 1655 | |8;json-parse-financial;53;54;52;52;54;53;54;56;54;53 05:32:58 INFO - PID 1655 | |9;json-stringify-tinderbox;61;62;69;92;99;94;88;71;60;61 05:32:58 INFO - PID 1655 | |10;stanford-crypto-aes;87;86;87;86;89;88;90;89;86;90 05:32:58 INFO - PID 1655 | |11;stanford-crypto-ccm;152;146;154;161;152;166;146;166;219;177 05:32:58 INFO - PID 1655 | |12;stanford-crypto-pbkdf2;204;198;190;239;255;201;196;194;197;178 05:32:58 INFO - PID 1655 | |13;stanford-crypto-sha256-iterative;72;94;86;73;72;77;92;77;71;72 05:32:58 INFO - PID 1655 | __end_tp_report 05:32:58 INFO - PID 1655 | __start_cc_report 05:32:58 INFO - PID 1655 | _x_x_mozilla_cycle_collect,999 05:32:58 INFO - PID 1655 | __end_cc_report 05:32:58 INFO - PID 1655 | __startTimestamp1491481978416__endTimestamp 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | ------- Summary: start ------- 05:32:58 INFO - PID 1655 | Number of tests: 14 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | [#0] ai-astar Cycles:10 Average:121.10 Median:122.00 stddev:4.43 (3.6%) stddev-sans-first:1.33 05:32:58 INFO - PID 1655 | Values: 109.0 122.0 121.0 124.0 123.0 122.0 122.0 122.0 121.0 125.0 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | [#1] audio-beat-detection Cycles:10 Average:124.40 Median:121.50 stddev:16.81 (13.8%) stddev-sans-first:13.71 05:32:58 INFO - PID 1655 | Values: 155.0 116.0 117.0 154.0 116.0 124.0 105.0 113.0 119.0 125.0 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | [#2] audio-dft Cycles:10 Average:234.90 Median:225.50 stddev:30.62 (13.6%) stddev-sans-first:31.62 05:32:58 INFO - PID 1655 | Values: 215.0 218.0 233.0 258.0 306.0 259.0 215.0 216.0 215.0 214.0 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | [#3] audio-fft Cycles:10 Average:77.10 Median:84.00 stddev:16.95 (20.2%) stddev-sans-first:17.52 05:32:58 INFO - PID 1655 | Values: 88.0 59.0 61.0 89.0 50.0 66.0 81.0 87.0 103.0 87.0 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:78.00 stddev:13.70 (17.6%) stddev-sans-first:13.99 05:32:58 INFO - PID 1655 | Values: 86.0 82.0 120.0 107.0 106.0 74.0 92.0 100.0 105.0 94.0 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:113.00 stddev:22.06 (19.5%) stddev-sans-first:22.32 05:32:58 INFO - PID 1655 | Values: 95.0 130.0 138.0 132.0 90.0 132.0 87.0 139.0 105.0 91.0 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | [#6] imaging-darkroom Cycles:10 Average:122.80 Median:129.00 stddev:23.28 (18.0%) stddev-sans-first:24.50 05:32:58 INFO - PID 1655 | Values: 131.0 143.0 109.0 162.0 127.0 101.0 101.0 103.0 151.0 100.0 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | [#7] imaging-desaturate Cycles:10 Average:120.90 Median:114.00 stddev:26.20 (23.0%) stddev-sans-first:26.78 05:32:58 INFO - PID 1655 | Values: 101.0 101.0 144.0 161.0 125.0 165.0 103.0 103.0 103.0 103.0 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:1.18 (2.2%) stddev-sans-first:1.24 05:32:58 INFO - PID 1655 | Values: 53.0 54.0 52.0 52.0 54.0 53.0 54.0 56.0 54.0 53.0 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:75.70 Median:79.50 stddev:15.73 (19.8%) stddev-sans-first:15.76 05:32:58 INFO - PID 1655 | Values: 61.0 62.0 69.0 92.0 99.0 94.0 88.0 71.0 60.0 61.0 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:88.50 stddev:1.62 (1.8%) stddev-sans-first:1.69 05:32:58 INFO - PID 1655 | Values: 87.0 86.0 87.0 86.0 89.0 88.0 90.0 89.0 86.0 90.0 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:163.90 Median:163.50 stddev:21.72 (13.3%) stddev-sans-first:22.61 05:32:58 INFO - PID 1655 | Values: 152.0 146.0 154.0 161.0 152.0 166.0 146.0 166.0 219.0 177.0 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.20 Median:199.50 stddev:23.43 (11.7%) stddev-sans-first:24.85 05:32:58 INFO - PID 1655 | Values: 204.0 198.0 190.0 239.0 255.0 201.0 196.0 194.0 197.0 178.0 05:32:58 INFO - PID 1655 | 05:32:58 INFO - PID 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.60 Median:77.00 stddev:8.80 (11.4%) stddev-sans-first:9.00 05:32:58 INFO - PID 1655 | Values: 72.0 94.0 86.0 73.0 72.0 77.0 92.0 77.0 71.0 72.0 05:32:58 INFO - PID 1655 | -------- Summary: end -------- 05:32:58 INFO - PID 1655 | 05:32:59 INFO - TEST-INFO | 1655: exit 0 05:32:59 INFO - TEST-OK | kraken | took 64179ms 05:32:59 INFO - SUITE-END | took 1181s 05:32:59 INFO - Completed test suite (00:19:41) 05:32:59 INFO - javascript benchmark 05:32:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7782.410856494728, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2983.433147701935, 3608.2061946371796, 3620.5655321361432, 3634.0, 3640.16276256461, 4948.430930443, 6191.473724733398, 6213.627182369055, 6231.408016238456, 6242.875142497159, 4642.907141857161, 6229.906551401733, 6230.1900752902175, 6250.343713910042, 6274.372562743723, 4777.976110119462, 5479.0, 5506.063969125258, 5517.0, 5606.103023516238, 5499.532539734128, 5569.637973531728, 5630.802921897734, 5633.549316054727, 5666.320041594994, 5443.945560544384, 5496.065668836268, 5545.445455454463, 5578.191162281447, 5620.241267428885, 4867.586255168279, 5204.29741984831, 5526.668399896019, 5527.474889885454, 5552.000639884819, 4507.2788353863225, 5437.564994800407, 5461.972690136524, 5472.644278121909, 5502.972485137549, 6033.5173186145, 6165.84585385369, 6202.224721909762, 6243.375662433766, 6249.500039996789, 6725.899111513331, 6783.93216067838, 6786.558873673195, 6790.0, 6795.082663840368, 6839.555428897106, 6895.207051189127, 6898.241193468714, 6898.827529311808, 6925.653717314164, 5144.71704056273, 5380.435054319319, 6298.118263443122, 6344.587601805868, 6347.143135676671, 6985.266547012592, 7007.299270072952, 7031.156261248683, 7041.647917604099, 7076.150861896605, 6337.049442583557, 6439.098526206335, 6447.806565803034, 6549.967250163719, 6635.601863888086, 6622.966885165543, 6733.730650774012, 6886.827829304308, 6899.8270294049125, 6908.5509441886115, 6565.770198043046, 6798.354156355138, 6800.319968003259, 6810.693518791666, 6823.249442561314, 5489.752961116679, 6440.194975628046, 6640.20317561886, 6685.264620891698, 6756.6959486822225, 5429.239906413105, 5452.0731475648445, 5459.344878614592, 5474.671519708831, 5490.423505531941, 4829.203181475087, 4850.6362022848425, 4860.076585448754, 4861.732604706775, 4881.926771098437, 5132.948670513247, 6056.121862329937, 6221.595596286227, 6256.374362563707, 6349.873002539924, 6791.558548694319, 6838.965805170942, 6873.243943166248, 6878.828029299208, 6890.069840571559, 6308.116863639094, 6345.650989195638, 6372.7132279047555, 6390.041493775971, 6402.295747467775, 5883.735231914574, 5961.642301461926, 5970.34326224115, 6091.878162436727, 6097.75608975636, 6888.104546408935, 6990.895136573057, 7055.329743674342, 7063.01117843492, 7084.752033678797, 6830.7267709293055, 6841.486888483483, 6860.451163906975, 6861.794146175623, 6873.0, 6454.644994525346, 7029.507934444539, 7083.0791997042115, 7105.25394833535, 7126.465515086285, 5977.133315669291, 6078.179445774941, 6424.743010279536, 6642.701078451578, 6685.33146685347, 5309.283246761638, 6820.965895170492, 6830.146231721035, 6920.377166055079, 6939.722411103499, 6345.936540634534, 6388.105665206782, 6440.710168042356, 6467.611943283418, 6545.345465453607, 6656.866862662817, 6662.56693314933, 6667.8666426670225, 6668.66656667155, 6682.164729408824, 6277.937220627735, 6406.359364063556, 6482.837929051811, 6512.772052978123, 6529.0, 4915.459299476983, 5748.827535173951, 5806.912896306643, 5859.414058594107, 6573.3098024706715, 5775.5090817281, 6473.643949582818, 6497.415232629086, 6499.512536559682, 6505.056766768697], "value": 6091.002891690222, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.147183640049, 797.3939805747632, 823.337213543098, 1253.918495297805, 1273.8407699037614, 37083.0, 38231.4265286021, 38261.61738382615, 38337.61662383372, 38375.80812095939, 32863.67136328636, 36782.08044798881, 37797.43303850437, 38091.61908380922, 38214.04464888379, 7932.365410767139, 8056.556889371082, 8442.62008209632, 8668.479891206516, 8693.347998900077, 8588.828223435528, 8625.396222264444, 8678.522681252529, 8688.782780430476, 8716.171963663443, 8167.101618821925, 8677.783055423602, 8706.782330441758, 8707.25988290996, 8722.08418116095, 8649.783755406103, 8675.26260267878, 8682.392232543725, 8705.521196334199, 8709.95645021777, 8423.241908228289, 8454.112318206562, 8481.406301558893, 8483.363747718946, 8485.95757021211, 37214.069648259014, 37294.81352593247, 37353.62646373529, 37450.06374840623, 37595.43606845899, 3474.3746125697367, 3515.2442224921765, 3516.771409858351, 3517.9472307915403, 3520.929581408383, 30240.0, 30274.243143921358, 30298.24254393658, 30359.392812143633, 30378.392432151453, 3082.321889184376, 3096.659367469599, 3125.078101959927, 3129.9687003129907, 3140.1364624728153, 241316.0, 246545.0, 288409.0, 290456.0, 300239.9999999978, 42297.942551435845, 50707.49292507101, 50906.745466272434, 51274.0, 51341.0, 52961.47038529565, 53103.734481327345, 53177.936441270955, 53198.468015320126, 53373.7331313341, 14319.997600168097, 14397.42410303597, 14513.782293265609, 14514.274286285854, 14626.341814618369, 11817.468213930393, 11823.408829558488, 11846.99300559462, 11852.0, 11870.821937670942, 13793.965452590895, 13829.861701383059, 13853.930730346485, 13935.721285574435, 14027.929860350632, 5464.371597266286, 6008.4592386684535, 6034.849128771816, 6079.361667024988, 6092.45167934888, 857.3612658569406, 882.3162049411629, 946.966856160031, 960.5869476125333, 1001.7996400719885, 22870.856457177073, 23354.7664523356, 23492.882535587214, 23666.526669466515, 23671.644925326134, 922.7693076730817, 942.9811403772023, 952.4333021851987, 959.390457954363, 1076.8492411062457, 12044.939775301244, 12053.57812476559, 12118.575849845389, 12192.939035304944, 12194.329311887932, 383385.0, 384841.0, 384869.0, 385437.0, 386611.0669446635, 51786.482135178165, 52313.47686523086, 52339.691507711854, 52364.501727318086, 52390.690232744484, 1278.213898452445, 1505.668752874366, 1513.8183417990133, 1513.9924300378427, 1525.2450037231642, 17294.913525432294, 21779.673304900756, 23459.94430250677, 23643.64534532018, 23808.52382952366, 35570.46644300286, 37061.258774823815, 37620.247595048495, 37680.623193767715, 37701.62298376981, 327111.0, 327118.36440817645, 328541.0, 328746.0, 329560.0, 26541.867290663424, 26882.73117268802, 31948.041558753273, 32541.674583253865, 33262.833685831414, 39448.0, 39671.2065758689, 39957.0, 40635.59364406318, 40757.98105047397, 27395.726042739316, 28985.275368115967, 29002.419951600426, 29016.12951611455, 29271.56092658569, 25371.492570148126, 28965.42069158563, 29042.0, 29136.854315728287, 29214.83140674349, 33591.66408335885, 33611.3277734439, 33825.15437114092, 33990.15024624404, 34490.482642759875, 28860.7113928858, 29195.70804292015, 29426.0, 29702.405951881272, 29824.10527684173, 17826.643467130845, 19277.90361048186, 21983.560328793013, 23741.406464838517, 23768.573885566922], "value": 17234.65026849494, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.2006228290813, 436.72922787871516, 438.6994908487684, 454.19380599436005, 454.21647657790317, 21667.783322166775, 21784.89107554462, 22508.3247502575, 22512.774872251273, 22657.54684906301, 21651.0, 21763.782362176375, 22211.888940555316, 22376.776232237673, 23150.95820688069, 5986.580939334249, 6081.331053584113, 6290.308066112724, 6293.874122517547, 6298.527610429213, 5294.894102117974, 5983.341832398432, 6172.197614310134, 6185.41238582334, 6212.1303017577575, 5256.290400795901, 5989.161517387569, 6070.301915279755, 6151.569390142692, 6175.197224360827, 6066.454019138277, 6142.938570614304, 6154.569180157391, 6189.133521307043, 6208.0, 4618.676692631517, 5370.865728356823, 5455.318085239345, 5485.451454854522, 5528.336599608033, 22601.886990565108, 27454.5881811773, 27669.723302767117, 27823.86088069567, 27969.86015069932, 1894.1571000904603, 1959.7354357161885, 2082.781307962657, 2099.496120930981, 2211.0050477285126, 16291.348346066261, 17930.5517362067, 18090.547736306697, 18440.170192341244, 18715.0, 2117.3224568138203, 2130.6803979403057, 2180.7928246816527, 2185.2024011235944, 2187.7593464719025, 122394.38802805929, 124434.0, 151053.9999999989, 158934.0, 160864.0, 11252.49363778632, 11336.149788765875, 11635.952764251258, 12788.93605531985, 12900.419916016834, 13428.798568021488, 16355.509334719978, 16691.99848009123, 16761.580960475843, 16767.83232167687, 8986.640534378552, 9188.724338269863, 9201.861972070425, 9270.0, 9397.812043759086, 8834.690785822466, 8890.244329231959, 9007.63969441228, 9023.368364214442, 9055.818883622422, 7917.485363451357, 7982.441229113906, 8022.598870056474, 8053.59732013397, 8079.959600201962, 2101.3905967269625, 2183.192218879025, 2193.6599827026835, 2195.7145571075657, 2201.5706937147424, 856.2507805670039, 886.4946980221212, 903.4714691905177, 928.9256859451227, 931.84624536952, 17925.820741792675, 21098.0, 21318.0, 21466.60309134993, 21502.78497215008, 951.6241084771615, 954.9161701468882, 983.4185263682832, 988.3871999360299, 998.7153661206667, 10541.262111652264, 10689.89310106889, 10737.087347575387, 10832.512536935857, 10842.566297348176, 707829.0, 720284.0, 723859.0, 727353.0, 738779.0, 155977.22011389871, 157675.0, 160635.39364606203, 164563.17718411572, 170873.0, 1163.9010684091777, 1219.9573014944435, 1263.9178453400684, 1266.1263728027632, 1268.536984000833, 19302.613947720685, 20679.379618611467, 21453.14187432485, 21722.782772172075, 22007.009684564553, 60196.09705854328, 64201.71596567949, 64498.0, 64631.0, 65599.34400656124, 73988.0, 86124.0, 87430.0, 87640.5617971906, 88045.0, 8818.470891746516, 8863.556822158736, 8907.910920890708, 8996.685116020908, 9396.295277854051, 13975.93012034974, 14081.929590351983, 14336.0, 14503.274836258355, 14553.85446145525, 10269.921658226078, 11628.94185529067, 11895.76208475843, 12026.398680066137, 12140.33228172459, 11308.886911130783, 11329.583802024747, 11407.429628518375, 11474.827877582009, 11566.65300041, 12203.389830508262, 12469.127161098999, 12508.186967847061, 12531.812022819482, 12556.87443125557, 8773.473591584525, 8983.595738191927, 9100.863487047567, 9155.954220228856, 9224.584893679666, 9651.517424128906, 10599.62901298451, 11903.345316007357, 12246.938765306117, 12323.75352492963], "value": 10634.302726377318, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1201.4413297816513, 1438.7266419380323, 1442.9278536073193, 1445.140141615739, 1453.7092581483703, 11477.885221147786, 11852.585159519396, 11862.051035917139, 11886.0, 12082.758344833099, 11779.882201178007, 11795.941020294908, 11848.111391645618, 11995.04039676826, 12034.33811140387, 2117.502386939072, 2499.3376755159807, 2504.2987963370288, 2513.949721005579, 2517.6097704855683, 2399.9880000599974, 2459.409741661997, 2501.024600405843, 2501.836645959628, 2505.3360859372283, 2451.6935383077116, 2456.1280745335416, 2468.1731619907264, 2491.9875400622973, 2499.4251322195905, 2436.293474892279, 2439.609662454008, 2444.2422848916717, 2451.6077427611585, 2505.1482495951464, 2380.2859142257425, 2422.467057247403, 2435.7198922124007, 2436.6954130733657, 2488.32815139913, 11736.413179340998, 11802.0, 11806.99640530556, 11821.054315654726, 11829.645110646694, 6449.258335291454, 6673.699683514205, 6718.126643536358, 6734.393904548565, 6793.218779840332, 3700.833462494167, 3910.1397692507603, 4199.118185181115, 4209.5790420957965, 4253.298680527782, 1236.3756303066893, 1241.8447694038246, 1273.6752128207247, 1289.9484020639163, 1294.6633875192517, 14959.626009349744, 15422.768658470244, 16530.00819950807, 16654.75017874733, 16677.583060423585, 4723.740194289278, 5520.475554822286, 5678.630888992285, 5687.829365119053, 5720.513756330675, 12337.50649973991, 12382.56661016878, 12420.62738117858, 12426.192297500633, 12428.192167509083, 5194.558462530652, 6327.272363678144, 6557.114789503451, 6560.901586476207, 6569.37590928861, 6108.755649774048, 6123.510119190454, 6234.719437625317, 6239.0, 6242.875142497124, 4055.330870406409, 4443.777811109431, 4454.688171828006, 4467.173572889026, 4530.682852200314, 1110.7278716714457, 1232.1744431231098, 1295.8315418995471, 1298.7142828577698, 1316.4470922212693, 630.621627023782, 681.5433659448181, 760.2853317881174, 766.7354762606892, 789.0570767932265, 5209.322788037531, 5931.110333450017, 5984.611000284967, 6198.473129783929, 6229.470495007975, 541.0963690636646, 543.071347994933, 548.4186762032259, 572.0960881806735, 574.6465923457043, 3472.9060345990933, 3589.940967414619, 3832.674222691047, 4076.205139997672, 4205.137946720914, 14584.0, 15151.621209469851, 15275.083494990336, 15311.387544498095, 15314.846851531565, 15132.470363537223, 15157.166355850533, 15179.316930738143, 15194.164320962233, 15231.695366092616, 10548.683539493828, 10966.561337546409, 11014.614488493025, 11151.163662725328, 11178.776424471465, 6408.58344207625, 6529.444997175293, 6581.07864898905, 6602.0, 6603.702833372606, 13140.934295328461, 13385.063045587105, 13756.931215343859, 13822.447102116204, 13858.722825543231, 12644.557440489538, 12858.549950751678, 14157.292135393394, 14163.079399838978, 14409.0, 6833.624150671761, 7264.891026634499, 8693.956530217309, 8764.474131552128, 8809.471431714117, 10926.0, 10935.070519005974, 11004.779904402027, 11035.779284414106, 11041.834372484258, 7701.6534255957495, 9151.267898568232, 9331.673391431268, 9361.4383137012, 9375.296852735935, 9077.046910074347, 9186.40288381253, 9205.585748641193, 9276.814463710552, 9380.530973451163, 8035.276825085771, 8104.27061564462, 8154.306883914696, 8169.754907352789, 8201.79495512617, 6007.579469437097, 6164.044573091178, 6180.412860778219, 6193.659348735682, 6313.210848643919, 5973.522118230618, 6170.352113028068, 6238.282597501346, 6258.812235632939, 6312.526560508072], "value": 5281.526768291962, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.6451477572954, 355.8007515791156, 373.79628102684035, 388.36114591497005, 400.5113761211323, 6186.772761805746, 6346.7461301548, 6397.520185986053, 6435.51733619978, 6480.449161821245, 6112.174856394386, 6265.867066466767, 6279.775443788463, 6338.0, 6364.377083843613, 4773.618110551165, 4778.9044219115685, 4795.136875362451, 4797.472278049412, 4811.807527698906, 4346.217680817452, 4444.97777511112, 4653.674242803005, 4755.595774359183, 4788.5450882166315, 3995.840166393341, 4436.622887054604, 4551.772411379435, 4552.521985191557, 4729.69256998294, 4535.614472769818, 4545.977270113662, 4556.270996640522, 4574.61115805157, 4586.54134586542, 3909.6346710240177, 3935.940960885589, 3937.7834219117935, 3937.98031009846, 3953.960460395388, 5966.643175945521, 6184.350643182491, 6220.253569571635, 6243.906341404882, 6244.3755624437645, 5015.699058056528, 5437.755301011464, 5439.782408703647, 5451.046066938309, 5456.536194423479, 5847.415258474119, 6691.397774200345, 6757.425618822406, 6771.932280677229, 6783.3216678332265, 4207.200631879933, 4405.691601587891, 4438.515765124362, 4444.777761111932, 4460.375547423363, 5927.37762534932, 6966.457243106281, 7159.57042577447, 7176.7846964591145, 7181.210066892638, 4400.0, 4408.232935712491, 4486.663500237495, 4495.280755079171, 4497.2129877271345, 4515.819367225275, 4592.494825569184, 4594.333821595917, 4599.5400459953735, 4607.792649330788, 4505.6846020778885, 4689.934870340025, 5335.679859208382, 5408.242846001563, 5442.673439593637, 5366.0, 5966.76132954677, 6001.278568003776, 6702.39678428934, 6800.183977922582, 1535.9648465782882, 1557.8082766683497, 1562.3828587707792, 1562.874970002397, 1565.6633823727952, 984.7193549838405, 1011.6256984915628, 1029.0995379043336, 1035.1098055672117, 1040.9687709368732, 6390.584612000205, 6396.424321810966, 6415.518836087312, 6433.871322573522, 6487.124238227779, 6676.86646267072, 7025.859482810315, 7027.329614746363, 7044.647767611701, 7069.964650176819, 6304.338044505353, 6314.421394651337, 6398.520110991694, 6398.632505972727, 6404.455621272244, 3663.578688450862, 3669.119411341259, 3672.320620685182, 3688.428293614494, 3730.6828919542145, 6171.259448866164, 6891.517593768388, 7123.9314102883945, 7135.0, 7272.963635181896, 6686.0276218515655, 6780.694868730919, 7245.456590755714, 7257.165425976081, 7257.786375155628, 5652.608434939086, 5690.516306113861, 5796.681182534917, 6353.999999999907, 7254.92745072553, 5398.433164517826, 5889.969255380377, 6661.634364955269, 7076.540024898365, 7095.432365410651, 5696.914546281892, 5704.828855134353, 6162.537809664383, 6894.8621027580175, 6976.7558135465015, 6221.880061588958, 6677.165354330708, 7029.718811247493, 7284.490085694162, 7285.417166626551, 4380.4305440292555, 4443.400140980926, 4505.256632655575, 4521.886952826206, 4522.841700540465, 5444.366689993066, 6629.867402652017, 6769.68285540072, 6780.762673306411, 7020.368166865007, 6678.565893216925, 6816.522843400914, 6826.590404575742, 6832.847652989292, 6848.623325716934, 6248.9062664059165, 6498.252700939263, 6800.693968771318, 6818.965905170641, 6822.761203357859, 4957.702537847741, 5784.248047753765, 6427.55007149495, 6637.6681165940545, 6672.5329226953645, 5269.71016594091, 6823.55646882951, 6848.8972665409065, 6874.900015997417, 6890.8621827562165, 5224.843254702365, 5783.566232532493, 6399.8720025598295, 6412.813629478562, 6709.530332876652], "value": 4849.834226770065, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.4249176855011, 815.2784785464862, 827.664795757718, 841.1966571923813, 843.2747836860301, 16250.593735156626, 16485.752713709306, 16536.7222050294, 17057.914710426463, 17096.059716715576, 15193.92403037986, 15540.378384864593, 15606.063636181809, 15844.0, 15847.0, 948.293521326611, 964.8456247000481, 968.3318510227955, 968.6851773173712, 970.7427531704104, 884.792073862645, 946.8485042393219, 980.3137803537517, 987.244757760314, 993.6720882108887, 1029.382370577655, 1037.5227395398126, 1045.994770026149, 1046.9162467002623, 1047.958081676736, 1028.3521381529627, 1034.9223808214338, 1038.8078205532001, 1042.2860340666668, 1042.598599539183, 759.5784339691469, 761.3832795435715, 766.8811334243201, 771.1170709537604, 772.0658003815334, 13156.0, 14096.365663545164, 16709.16454177286, 16731.6653666926, 17453.12734363277, 14892.180930048842, 15018.0, 15126.924365378103, 15157.242137893172, 15743.763843542356, 13356.0, 13426.932865335708, 13677.794833077513, 13686.31568421575, 13794.379252933642, 3351.7151042161445, 3360.663933606644, 3363.394588973984, 3394.0666316763086, 3396.3886500429912, 118778.0, 120091.79908200893, 121869.0, 122100.0, 122371.0, 7911.28798408146, 10511.421871796972, 11617.672769050278, 11722.648320550397, 12169.574064907685, 24075.0, 24329.27012189637, 24433.51132977366, 24588.0, 24612.0, 8016.799580010546, 8059.798505037421, 8072.233137851895, 8361.749147525585, 8468.703595374256, 3537.557805274341, 4120.62914337711, 4165.75005499671, 4176.102138040336, 4185.100203456211, 4119.6910231732145, 4203.600657937491, 4215.78921053946, 4231.0, 4232.090100628318, 906.2795077913075, 934.6027938126269, 956.8086382723344, 958.5494817435793, 959.1128206409044, 595.5979713693326, 618.8267285160163, 625.524601303013, 631.1164369882201, 655.429776094791, 10086.844004279961, 10182.23633227496, 10262.897371026344, 10391.428471434143, 10567.032009152468, 561.6517758989453, 605.5246631394423, 608.8751805879873, 613.5918068033873, 637.8998069382795, 4798.73701769367, 4800.711957282504, 4819.469858315582, 4846.0, 4850.903293841592, 79429.60285198652, 98127.0, 102426.0, 102549.0, 104183.0, 76464.0, 76553.0, 87888.0, 89141.66287505563, 91799.5410022959, 59499.70250148721, 70744.0, 70889.0, 74944.62527687436, 75471.24528754751, 9796.9020309796, 9939.453330066626, 10158.847617285599, 10209.591616335263, 10693.786124277316, 38283.425748613234, 38994.80502597469, 39770.80114599408, 40254.0, 40917.56788512388, 44305.778471107435, 45617.31574026326, 51399.97200056053, 51973.0, 52213.0, 14159.292035398395, 14181.0, 14294.571162865132, 14491.56566287642, 14501.637459063608, 14857.628559285671, 15031.924840375728, 15093.255393456284, 15306.540803775904, 15842.287097080429, 13868.167909925436, 13904.175543459021, 14018.088824226392, 14134.646633834236, 15119.697606048037, 14012.299385030912, 14060.367283472473, 14133.679428942327, 14143.0, 14562.635934101732, 12087.576934807239, 13935.721285574435, 14229.857701423267, 14284.510407526666, 14711.852881471048, 9865.210783137189, 10176.287659863738, 10250.538725757508, 10619.893801061891, 10798.67603971882, 10493.632722854663, 11076.560047194136, 11280.887191127984, 11383.772324553629, 11449.370284634091], "value": 7769.05270820401, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1676.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 122.0, 121.0, 124.0, 123.0, 122.0, 122.0, 122.0, 121.0, 125.0], "value": 121.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 116.0, 117.0, 154.0, 116.0, 124.0, 105.0, 113.0, 119.0, 125.0], "value": 124.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [215.0, 218.0, 233.0, 258.0, 306.0, 259.0, 215.0, 216.0, 215.0, 214.0], "value": 234.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 59.0, 61.0, 89.0, 50.0, 66.0, 81.0, 87.0, 103.0, 87.0], "value": 77.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 120.0, 107.0, 106.0, 74.0, 92.0, 100.0, 105.0, 94.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 130.0, 138.0, 132.0, 90.0, 132.0, 87.0, 139.0, 105.0, 91.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 143.0, 109.0, 162.0, 127.0, 101.0, 101.0, 103.0, 151.0, 100.0], "value": 122.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 101.0, 144.0, 161.0, 125.0, 165.0, 103.0, 103.0, 103.0, 103.0], "value": 120.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 52.0, 52.0, 54.0, 53.0, 54.0, 56.0, 54.0, 53.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 69.0, 92.0, 99.0, 94.0, 88.0, 71.0, 60.0, 61.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 86.0, 89.0, 88.0, 90.0, 89.0, 86.0, 90.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 146.0, 154.0, 161.0, 152.0, 166.0, 146.0, 166.0, 219.0, 177.0], "value": 163.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 198.0, 190.0, 239.0, 255.0, 201.0, 196.0, 194.0, 197.0, 178.0], "value": 205.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 94.0, 86.0, 73.0, 72.0, 77.0, 92.0, 77.0, 71.0, 72.0], "value": 78.6, "unit": "score"}]}]} 05:32:59 INFO - Return code: 0 05:32:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:32:59 ERROR - # TBPL SUCCESS # 05:32:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:32:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:32:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:32:59 INFO - 2017-04-06 05:32:59.745 screenresolution[1660:10818] starting screenresolution argv=screenresolution get 05:32:59 INFO - 2017-04-06 05:32:59.748 screenresolution[1660:10818] Display 0: 1600x1200x32@60 05:32:59 INFO - 2017-04-06 05:32:59.754 screenresolution[1661:10821] starting screenresolution argv=screenresolution list 05:32:59 INFO - Available Modes on Display 0 05:32:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:32:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:32:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:32:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:32:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:32:59 INFO - Intel Iris: 05:32:59 INFO - Chipset Model: Intel Iris 05:32:59 INFO - Type: GPU 05:32:59 INFO - Bus: Built-In 05:32:59 INFO - VRAM (Dynamic, Max): 1536 MB 05:32:59 INFO - Vendor: Intel (0x8086) 05:32:59 INFO - Device ID: 0x0a2e 05:32:59 INFO - Revision ID: 0x0009 05:32:59 INFO - Displays: 05:32:59 INFO - Display: 05:32:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:32:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:32:59 INFO - Display Serial Number: 1600x1200 60 05:32:59 INFO - Main Display: Yes 05:32:59 INFO - Mirror: Off 05:32:59 INFO - Online: Yes 05:32:59 INFO - Rotation: Supported 05:32:59 INFO - Return code: 0 05:32:59 INFO - Running post-action listener: _resource_record_post_action 05:32:59 INFO - [mozharness: 2017-04-06 12:32:59.998351Z] Finished run-tests step (success) 05:32:59 INFO - Running post-run listener: _resource_record_post_run 05:33:00 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 38812160; Write bytes: 518728704; Read time: 381; Write time: 1705 05:33:00 INFO - TinderboxPrint: CPU usage
54.2% 05:33:00 INFO - TinderboxPrint: I/O read bytes / time
38,812,160 / 381 05:33:00 INFO - TinderboxPrint: I/O write bytes / time
518,728,704 / 1,705 05:33:00 INFO - TinderboxPrint: CPU idle
2,200.9 (45.8%) 05:33:00 INFO - TinderboxPrint: CPU system
968.6 (20.1%) 05:33:00 INFO - TinderboxPrint: CPU user
1,640.1 (34.1%) 05:33:00 INFO - TinderboxPrint: Swap in / out
246,067,200 / 0 05:33:00 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 204876288; Write bytes: 204867584; Read time: 15897; Write time: 488 05:33:00 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 36817408; Write bytes: 306975744; Read time: 360; Write time: 1178 05:33:00 INFO - Running post-run listener: _upload_blobber_files 05:33:00 INFO - Blob upload gear active. 05:33:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:33:00 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 05:33:00 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'] 05:33:00 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 05:33:00 INFO - (blobuploader) - INFO - Open directory for files ... 05:33:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:33:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:33:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:01 INFO - (blobuploader) - INFO - Done attempting. 05:33:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:33:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:33:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:02 INFO - (blobuploader) - INFO - Done attempting. 05:33:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:33:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:33:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:03 INFO - (blobuploader) - INFO - Done attempting. 05:33:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:33:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:33:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:05 INFO - (blobuploader) - INFO - Done attempting. 05:33:05 INFO - (blobuploader) - INFO - Iteration through files over. 05:33:05 INFO - Return code: 0 05:33:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:33:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:33:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d07f26af3489a9148126760788708bc7fdfad9823b0587e4cb931fb26b97348cd97060fff0cd1fa7d1e605b60a0a4897ccc12a54b47faa57b8325ccf32566d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a710acbbb6d85d8173816158ada9eccda89676c4ca4dcfefe888ba37bca490923ad78b804deac720cd11b1a1808c81809161e78d0750845e43172e0073b95a73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e8280496b24d64be7a49862bf0400d809fd895d9fd9763a3377023f64b0038d83c4ad3e88bd9e92168723569aaceb0d450381083c0283738eec5d5dac5bc4c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e99b4889d4c6dbed22ffe0fadac189f0a89d344cdac788b98c6473d403a66f8780572b412fe0cd4f27d43a77e05cdaca69b5ce68ecb243f1561c0f697281deb"} 05:33:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:33:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:33:05 INFO - Contents: 05:33:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d07f26af3489a9148126760788708bc7fdfad9823b0587e4cb931fb26b97348cd97060fff0cd1fa7d1e605b60a0a4897ccc12a54b47faa57b8325ccf32566d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a710acbbb6d85d8173816158ada9eccda89676c4ca4dcfefe888ba37bca490923ad78b804deac720cd11b1a1808c81809161e78d0750845e43172e0073b95a73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e8280496b24d64be7a49862bf0400d809fd895d9fd9763a3377023f64b0038d83c4ad3e88bd9e92168723569aaceb0d450381083c0283738eec5d5dac5bc4c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e99b4889d4c6dbed22ffe0fadac189f0a89d344cdac788b98c6473d403a66f8780572b412fe0cd4f27d43a77e05cdaca69b5ce68ecb243f1561c0f697281deb"} 05:33:05 INFO - Running post-run listener: copy_logs_to_upload_dir 05:33:05 INFO - Copying logs to upload dir... 05:33:05 INFO - mkdir: /builds/slave/test/build/upload/logs 05:33:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.575188 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-06 05:33:06.015880) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 05:33:06.019516) ========= 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.aP6adHBFKU/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.12swxwcZKm/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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/8d07f26af3489a9148126760788708bc7fdfad9823b0587e4cb931fb26b97348cd97060fff0cd1fa7d1e605b60a0a4897ccc12a54b47faa57b8325ccf32566d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a710acbbb6d85d8173816158ada9eccda89676c4ca4dcfefe888ba37bca490923ad78b804deac720cd11b1a1808c81809161e78d0750845e43172e0073b95a73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e8280496b24d64be7a49862bf0400d809fd895d9fd9763a3377023f64b0038d83c4ad3e88bd9e92168723569aaceb0d450381083c0283738eec5d5dac5bc4c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e99b4889d4c6dbed22ffe0fadac189f0a89d344cdac788b98c6473d403a66f8780572b412fe0cd4f27d43a77e05cdaca69b5ce68ecb243f1561c0f697281deb"} build_url:https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010608 build_url: 'https://queue.taskcluster.net/v1/task/RUue8Iu6RJuBGsYw6Tpn8g/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/8d07f26af3489a9148126760788708bc7fdfad9823b0587e4cb931fb26b97348cd97060fff0cd1fa7d1e605b60a0a4897ccc12a54b47faa57b8325ccf32566d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a710acbbb6d85d8173816158ada9eccda89676c4ca4dcfefe888ba37bca490923ad78b804deac720cd11b1a1808c81809161e78d0750845e43172e0073b95a73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e8280496b24d64be7a49862bf0400d809fd895d9fd9763a3377023f64b0038d83c4ad3e88bd9e92168723569aaceb0d450381083c0283738eec5d5dac5bc4c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e99b4889d4c6dbed22ffe0fadac189f0a89d344cdac788b98c6473d403a66f8780572b412fe0cd4f27d43a77e05cdaca69b5ce68ecb243f1561c0f697281deb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 05:33:06.052174) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:33:06.052507) ========= 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.aP6adHBFKU/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.12swxwcZKm/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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.004872 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:33:06.101513) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 05:33:06.101907) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 05:33:06.102307) ========= ========= Total master_lag: 0.18 =========