builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0087 starttime: 1486763288.94 results: success (0) buildid: 20170210123623 builduid: 7de64d49336f444ea24f88f7aa023c9f revision: a288fe35e494cec3620717eab66eaff6f68a6369 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-10 13:48:08.941732) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-10 13:48:08.942221) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-10 13:48:08.942514) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-10 13:48:08.970046) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 13:48:08.970455) ========= 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.fF3883ew3A/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.JfQcI5Fl1s/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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-02-10 13:48:09-- 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% 14.6M=0.001s 2017-02-10 13:48:09 (14.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121910 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 13:48:09.116722) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 13:48:09.117038) ========= 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.fF3883ew3A/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.JfQcI5Fl1s/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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.031675 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 13:48:09.173131) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-10 13:48:09.173497) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a288fe35e494cec3620717eab66eaff6f68a6369 --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 a288fe35e494cec3620717eab66eaff6f68a6369 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fF3883ew3A/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.JfQcI5Fl1s/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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-02-10 13:48:09,287 truncating revision to first 12 chars 2017-02-10 13:48:09,287 Setting DEBUG logging. 2017-02-10 13:48:09,287 attempt 1/10 2017-02-10 13:48:09,287 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a288fe35e494?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-10 13:48:10,370 unpacking tar archive at: mozilla-central-a288fe35e494/testing/mozharness/ program finished with exit code 0 elapsedTime=1.353134 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-10 13:48:10.553023) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-10 13:48:10.553365) ========= script_repo_revision: a288fe35e494cec3620717eab66eaff6f68a6369 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-10 13:48:10.553750) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-10 13:48:10.554015) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-10 13:48:10.573962) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-10 13:48:10.574386) ========= /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.fF3883ew3A/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.JfQcI5Fl1s/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 13:48:10 INFO - MultiFileLogger online at 20170210 13:48:10 in /builds/slave/test 13:48:10 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 13:48:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:48:10 INFO - {'append_to_log': False, 13:48:10 INFO - 'base_work_dir': '/builds/slave/test', 13:48:10 INFO - 'blob_upload_branch': 'Firefox', 13:48:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:48:10 INFO - 'branch': 'Firefox', 13:48:10 INFO - 'buildbot_json_path': 'buildprops.json', 13:48:10 INFO - 'config_files': ('talos/mac_config.py',), 13:48:10 INFO - 'default_actions': ('clobber', 13:48:10 INFO - 'read-buildbot-config', 13:48:10 INFO - 'download-and-extract', 13:48:10 INFO - 'populate-webroot', 13:48:10 INFO - 'create-virtualenv', 13:48:10 INFO - 'install', 13:48:10 INFO - 'run-tests'), 13:48:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:48:10 INFO - 'download_minidump_stackwalk': True, 13:48:10 INFO - 'download_symbols': 'ondemand', 13:48:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:48:10 INFO - 'tooltool.py': '/tools/tooltool.py', 13:48:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:48:10 INFO - '/tools/misc-python/virtualenv.py')}, 13:48:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:48:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:48:10 INFO - 'installer_path': 'installer.exe', 13:48:10 INFO - 'log_level': 'info', 13:48:10 INFO - 'log_name': 'talos', 13:48:10 INFO - 'log_to_console': True, 13:48:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:48:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:48:10 INFO - 'opt_config_files': (), 13:48:10 INFO - 'pip_index': False, 13:48:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:48:10 INFO - 'cmd': ('bash', 13:48:10 INFO - '-c', 13:48:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:48:10 INFO - 'enabled': True, 13:48:10 INFO - 'halt_on_failure': False, 13:48:10 INFO - 'name': 'check_screen_resolution'},), 13:48:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:48:10 INFO - 'cmd': ('bash', 13:48:10 INFO - '-c', 13:48:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:48:10 INFO - 'enabled': True, 13:48:10 INFO - 'halt_on_failure': False, 13:48:10 INFO - 'name': 'check_screen_resolution'},), 13:48:10 INFO - 'run_cmd_checks_enabled': True, 13:48:10 INFO - 'sps_profile': False, 13:48:10 INFO - 'sps_profile_interval': 0, 13:48:10 INFO - 'suite': 'dromaeojs-e10s', 13:48:10 INFO - 'system_bits': '32', 13:48:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:48:10 INFO - 'title': 't-yosemite-r7-0087', 13:48:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:48:10 INFO - 'use_talos_json': True, 13:48:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:48:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:48:10 INFO - 'work_dir': 'build'} 13:48:10 INFO - [mozharness: 2017-02-10 21:48:10.789523Z] Running clobber step. 13:48:10 INFO - Running pre-action listener: _resource_record_pre_action 13:48:10 INFO - Running main action method: clobber 13:48:10 INFO - rmtree: /builds/slave/test/build 13:48:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:48:13 INFO - Running post-action listener: _resource_record_post_action 13:48:13 INFO - [mozharness: 2017-02-10 21:48:13.217902Z] Finished clobber step (success) 13:48:13 INFO - [mozharness: 2017-02-10 21:48:13.218069Z] Running read-buildbot-config step. 13:48:13 INFO - Running pre-action listener: _resource_record_pre_action 13:48:13 INFO - Running main action method: read_buildbot_config 13:48:13 INFO - Using buildbot properties: 13:48:13 INFO - { 13:48:13 INFO - "project": "", 13:48:13 INFO - "product": "firefox", 13:48:13 INFO - "script_repo_revision": "production", 13:48:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 13:48:13 INFO - "repository": "", 13:48:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 13:48:13 INFO - "buildid": "20170210123623", 13:48:13 INFO - "pgo_build": "False", 13:48:13 INFO - "basedir": "/builds/slave/test", 13:48:13 INFO - "buildnumber": 86, 13:48:13 INFO - "slavename": "t-yosemite-r7-0087", 13:48:13 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:48:13 INFO - "platform": "yosemite_r7", 13:48:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 13:48:13 INFO - "branch": "mozilla-central", 13:48:13 INFO - "repo_path": "mozilla-central", 13:48:13 INFO - "revision": "a288fe35e494cec3620717eab66eaff6f68a6369", 13:48:13 INFO - "stage_platform": "macosx64", 13:48:13 INFO - "builduid": "7de64d49336f444ea24f88f7aa023c9f", 13:48:13 INFO - "slavebuilddir": "test" 13:48:13 INFO - } 13:48:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 13:48:13 INFO - Running post-action listener: _resource_record_post_action 13:48:13 INFO - [mozharness: 2017-02-10 21:48:13.221537Z] Finished read-buildbot-config step (success) 13:48:13 INFO - [mozharness: 2017-02-10 21:48:13.221674Z] Running download-and-extract step. 13:48:13 INFO - Running pre-action listener: _resource_record_pre_action 13:48:13 INFO - Running main action method: download_and_extract 13:48:13 INFO - mkdir: /builds/slave/test/build/tests 13:48:13 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')]} 13:48:13 INFO - trying https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 13:48:13 INFO - Downloading https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 13:48:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 13:48:14 INFO - Downloaded 1199 bytes. 13:48:14 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 13:48:14 INFO - Using the following test package requirements: 13:48:14 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 13:48:14 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:48:14 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 13:48:14 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:48:14 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 13:48:14 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:48:14 INFO - u'jsshell-mac.zip'], 13:48:14 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:48:14 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 13:48:14 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 13:48:14 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:48:14 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 13:48:14 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:48:14 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 13:48:14 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:48:14 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 13:48:14 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 13:48:14 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 13:48:14 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:48:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 13:48:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:48:14 INFO - Fetch https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 13:48:15 INFO - Expected file size: 34948708 13:48:15 INFO - Obtained file size: 34948708 13:48:22 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:48:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 13:48:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:48:22 INFO - Fetch https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 13:48:24 INFO - Expected file size: 34948708 13:48:24 INFO - Obtained file size: 34948708 13:48:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 13:48:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:48:30 INFO - Fetch https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 13:48:32 INFO - Expected file size: 14403454 13:48:32 INFO - Obtained file size: 14403454 13:48: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')]} 13:48:32 INFO - trying https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 13:48:32 INFO - Downloading https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 13:48:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 13:48:34 INFO - Downloaded 70482046 bytes. 13:48:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 13:48:34 INFO - mkdir: /builds/slave/test/properties 13:48:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:48:34 INFO - Writing to file /builds/slave/test/properties/build_url 13:48:34 INFO - Contents: 13:48:34 INFO - build_url:https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 13:48:35 INFO - Running post-action listener: _resource_record_post_action 13:48:35 INFO - Running post-action listener: set_extra_try_arguments 13:48:35 INFO - [mozharness: 2017-02-10 21:48:35.041138Z] Finished download-and-extract step (success) 13:48:35 INFO - [mozharness: 2017-02-10 21:48:35.041277Z] Running populate-webroot step. 13:48:35 INFO - Running pre-action listener: _resource_record_pre_action 13:48:35 INFO - Running main action method: populate_webroot 13:48:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:48:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:48:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:48:35 INFO - '--tppagecycles', 13:48:35 INFO - '7'], 13:48:35 INFO - 'tests': ['tsvgm']}}, 13:48:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:48:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:48:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:48:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:48:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:48:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:48:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:48:35 INFO - 'talos_options': ['--disable-e10s'], 13:48:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:48:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:48:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:48:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:48:35 INFO - 'talos_options': ['--disable-e10s'], 13:48:35 INFO - 'tests': ['damp', 'tps']}, 13:48:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:48:35 INFO - 'tests': ['damp', 'tps']}, 13:48:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:48:35 INFO - 'tests': ['dromaeo_dom']}, 13:48:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:48:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:48:35 INFO - 'tests': ['basic_compositor_video']}, 13:48:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:48:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:48:35 INFO - 'tests': ['a11yr', 13:48:35 INFO - 'ts_paint', 13:48:35 INFO - 'tpaint', 13:48:35 INFO - 'sessionrestore', 13:48:35 INFO - 'sessionrestore_no_auto_restore', 13:48:35 INFO - 'tabpaint']}, 13:48:35 INFO - 'other-e10s': {'tests': ['a11yr', 13:48:35 INFO - 'ts_paint', 13:48:35 INFO - 'tpaint', 13:48:35 INFO - 'sessionrestore', 13:48:35 INFO - 'sessionrestore_no_auto_restore', 13:48:35 INFO - 'tabpaint']}, 13:48:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:48:35 INFO - 'ts_paint', 13:48:35 INFO - 'tpaint', 13:48:35 INFO - 'sessionrestore', 13:48:35 INFO - 'sessionrestore_no_auto_restore', 13:48:35 INFO - 'tabpaint']}, 13:48:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:48:35 INFO - 'ts_paint', 13:48:35 INFO - 'tpaint', 13:48:35 INFO - 'sessionrestore', 13:48:35 INFO - 'sessionrestore_no_auto_restore', 13:48:35 INFO - 'tabpaint']}, 13:48:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:48:35 INFO - 'tests': ['a11yr', 13:48:35 INFO - 'ts_paint', 13:48:35 INFO - 'tpaint', 13:48:35 INFO - 'sessionrestore', 13:48:35 INFO - 'sessionrestore_no_auto_restore', 13:48:35 INFO - 'tabpaint']}, 13:48:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:48:35 INFO - 'tests': ['a11yr', 13:48:35 INFO - 'ts_paint', 13:48:35 INFO - 'tpaint', 13:48:35 INFO - 'sessionrestore', 13:48:35 INFO - 'sessionrestore_no_auto_restore', 13:48:35 INFO - 'tabpaint']}, 13:48:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:48:35 INFO - 'tests': ['tsvgx', 13:48:35 INFO - 'tsvgr_opacity', 13:48:35 INFO - 'tart', 13:48:35 INFO - 'tscrollx', 13:48:35 INFO - 'cart', 13:48:35 INFO - 'tsvg_static']}, 13:48:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:48:35 INFO - 'tsvgr_opacity', 13:48:35 INFO - 'tart', 13:48:35 INFO - 'tscrollx', 13:48:35 INFO - 'cart', 13:48:35 INFO - 'tsvg_static']}, 13:48:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:48:35 INFO - 'talos_options': ['--disable-e10s'], 13:48:35 INFO - 'tests': ['tp5o']}, 13:48:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:48:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:48:35 INFO - 'talos_options': ['--disable-e10s', 13:48:35 INFO - '--xperf_path', 13:48:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:48:35 INFO - 'tests': ['tp5n']}, 13:48:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:48:35 INFO - 'talos_options': ['--xperf_path', 13:48:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:48:35 INFO - 'tests': ['tp5n']}}, 13:48:35 INFO - 'talos.zip': {'path': '', 13:48:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:48:35 INFO - Running post-action listener: _resource_record_post_action 13:48:35 INFO - [mozharness: 2017-02-10 21:48:35.059230Z] Finished populate-webroot step (success) 13:48:35 INFO - [mozharness: 2017-02-10 21:48:35.059348Z] Running create-virtualenv step. 13:48:35 INFO - Running pre-action listener: _resource_record_pre_action 13:48:35 INFO - Running main action method: create_virtualenv 13:48:35 INFO - Creating virtualenv /builds/slave/test/build/venv 13:48:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:48:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:48:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:48:35 INFO - Using real prefix '/tools/python27' 13:48:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:48:35 INFO - Installing distribute.............................................................................................................................................................................................done. 13:48:38 INFO - Installing pip.................done. 13:48:38 INFO - Return code: 0 13:48:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:48:39 INFO - Reading from file tmpfile_stdout 13:48:39 INFO - Output received: 13:48:39 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 13:48:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:48:39 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')]} 13:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:39 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')]} 13:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:39 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 0x10d7c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf3d8f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d59ed30>, '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 0x10d4c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d593be8>, '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.fF3883ew3A/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.JfQcI5Fl1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:39 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 13:48:39 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 13:48:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fF3883ew3A/Render', 13:48:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:39 INFO - 'HOME': '/Users/cltbld', 13:48:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:39 INFO - 'LOGNAME': 'cltbld', 13:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:39 INFO - 'MOZ_NO_REMOTE': '1', 13:48:39 INFO - 'NO_EM_RESTART': '1', 13:48:39 INFO - 'PAGER': '/bin/cat', 13:48:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:39 INFO - 'PWD': '/builds/slave/test', 13:48:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:39 INFO - 'SHELL': '/bin/bash', 13:48:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JfQcI5Fl1s/Listeners', 13:48:39 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 13:48:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:39 INFO - 'USER': 'cltbld', 13:48:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:39 INFO - 'XPC_FLAGS': '0x0', 13:48:39 INFO - 'XPC_SERVICE_NAME': '0', 13:48:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:48:39 INFO - Cleaning up... 13:48:39 INFO - Return code: 0 13:48:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:48:39 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')]} 13:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:39 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')]} 13:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:39 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 0x10d7c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf3d8f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d59ed30>, '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 0x10d4c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d593be8>, '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.fF3883ew3A/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.JfQcI5Fl1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:39 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 13:48:39 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 13:48:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fF3883ew3A/Render', 13:48:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:39 INFO - 'HOME': '/Users/cltbld', 13:48:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:39 INFO - 'LOGNAME': 'cltbld', 13:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:39 INFO - 'MOZ_NO_REMOTE': '1', 13:48:39 INFO - 'NO_EM_RESTART': '1', 13:48:39 INFO - 'PAGER': '/bin/cat', 13:48:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:39 INFO - 'PWD': '/builds/slave/test', 13:48:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:39 INFO - 'SHELL': '/bin/bash', 13:48:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JfQcI5Fl1s/Listeners', 13:48:39 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 13:48:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:39 INFO - 'USER': 'cltbld', 13:48:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:39 INFO - 'XPC_FLAGS': '0x0', 13:48:39 INFO - 'XPC_SERVICE_NAME': '0', 13:48:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:39 INFO - Downloading/unpacking psutil>=3.1.1 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:48:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:48:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:48:43 INFO - Installing collected packages: psutil 13:48:43 INFO - Running setup.py install for psutil 13:48:43 INFO - building 'psutil._psutil_osx' extension 13:48:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:48:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:48:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:48:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:48:44 INFO - building 'psutil._psutil_posix' extension 13:48:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:48:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:48:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:48:44 INFO - ^ 13:48:44 INFO - 1 warning generated. 13:48:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:48:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:48:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:48:44 INFO - Successfully installed psutil 13:48:44 INFO - Cleaning up... 13:48:44 INFO - Return code: 0 13:48:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:48:44 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')]} 13:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:44 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')]} 13:48:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:44 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 0x10d7c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf3d8f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d59ed30>, '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 0x10d4c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d593be8>, '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.fF3883ew3A/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.JfQcI5Fl1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:44 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 13:48:44 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 13:48:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fF3883ew3A/Render', 13:48:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:44 INFO - 'HOME': '/Users/cltbld', 13:48:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:44 INFO - 'LOGNAME': 'cltbld', 13:48:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:44 INFO - 'MOZ_NO_REMOTE': '1', 13:48:44 INFO - 'NO_EM_RESTART': '1', 13:48:44 INFO - 'PAGER': '/bin/cat', 13:48:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:44 INFO - 'PWD': '/builds/slave/test', 13:48:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:44 INFO - 'SHELL': '/bin/bash', 13:48:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JfQcI5Fl1s/Listeners', 13:48:44 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 13:48:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:44 INFO - 'USER': 'cltbld', 13:48:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:44 INFO - 'XPC_FLAGS': '0x0', 13:48:44 INFO - 'XPC_SERVICE_NAME': '0', 13:48:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:48:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:48:47 INFO - Installing collected packages: mozsystemmonitor 13:48:47 INFO - Running setup.py install for mozsystemmonitor 13:48:47 INFO - Successfully installed mozsystemmonitor 13:48:47 INFO - Cleaning up... 13:48:47 INFO - Return code: 0 13:48:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:48:47 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')]} 13:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:47 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')]} 13:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:47 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 0x10d7c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf3d8f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d59ed30>, '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 0x10d4c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d593be8>, '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.fF3883ew3A/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.JfQcI5Fl1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:47 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 13:48:47 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 13:48:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fF3883ew3A/Render', 13:48:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:47 INFO - 'HOME': '/Users/cltbld', 13:48:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:47 INFO - 'LOGNAME': 'cltbld', 13:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:47 INFO - 'MOZ_NO_REMOTE': '1', 13:48:47 INFO - 'NO_EM_RESTART': '1', 13:48:47 INFO - 'PAGER': '/bin/cat', 13:48:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:47 INFO - 'PWD': '/builds/slave/test', 13:48:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:47 INFO - 'SHELL': '/bin/bash', 13:48:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JfQcI5Fl1s/Listeners', 13:48:47 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 13:48:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:47 INFO - 'USER': 'cltbld', 13:48:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:47 INFO - 'XPC_FLAGS': '0x0', 13:48:47 INFO - 'XPC_SERVICE_NAME': '0', 13:48:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:47 INFO - Downloading/unpacking jsonschema==2.5.1 13:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:47 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 13:48:47 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 13:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:48:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:50 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 13:48:50 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 13:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:51 INFO - Downloading functools32-3.2.3-2.tar.gz 13:48:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:48:51 INFO - warning: no files found matching '*.txt' 13:48:51 INFO - no previously-included directories found matching 'build' 13:48:51 INFO - no previously-included directories found matching 'dist' 13:48:51 INFO - no previously-included directories found matching '.git*' 13:48:51 INFO - Installing collected packages: jsonschema, functools32 13:48:51 INFO - Running setup.py install for functools32 13:48:51 INFO - warning: no files found matching '*.txt' 13:48:51 INFO - no previously-included directories found matching 'build' 13:48:51 INFO - no previously-included directories found matching 'dist' 13:48:51 INFO - no previously-included directories found matching '.git*' 13:48:51 INFO - Successfully installed jsonschema functools32 13:48:51 INFO - Cleaning up... 13:48:51 INFO - Return code: 0 13:48:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:48:51 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')]} 13:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:51 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')]} 13:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10d7c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf3d8f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d59ed30>, '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 0x10d4c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d593be8>, '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.fF3883ew3A/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.JfQcI5Fl1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:51 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 13:48:51 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 13:48:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fF3883ew3A/Render', 13:48:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:51 INFO - 'HOME': '/Users/cltbld', 13:48:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:51 INFO - 'LOGNAME': 'cltbld', 13:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:51 INFO - 'MOZ_NO_REMOTE': '1', 13:48:51 INFO - 'NO_EM_RESTART': '1', 13:48:51 INFO - 'PAGER': '/bin/cat', 13:48:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:51 INFO - 'PWD': '/builds/slave/test', 13:48:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:51 INFO - 'SHELL': '/bin/bash', 13:48:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JfQcI5Fl1s/Listeners', 13:48:51 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 13:48:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:51 INFO - 'USER': 'cltbld', 13:48:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:51 INFO - 'XPC_FLAGS': '0x0', 13:48:51 INFO - 'XPC_SERVICE_NAME': '0', 13:48:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:48:51 INFO - Cleaning up... 13:48:51 INFO - Return code: 0 13:48:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:48:51 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')]} 13:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:51 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')]} 13:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10d7c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf3d8f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d59ed30>, '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 0x10d4c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d593be8>, '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.fF3883ew3A/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.JfQcI5Fl1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:51 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 13:48:51 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 13:48:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fF3883ew3A/Render', 13:48:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:51 INFO - 'HOME': '/Users/cltbld', 13:48:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:51 INFO - 'LOGNAME': 'cltbld', 13:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:51 INFO - 'MOZ_NO_REMOTE': '1', 13:48:51 INFO - 'NO_EM_RESTART': '1', 13:48:51 INFO - 'PAGER': '/bin/cat', 13:48:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:51 INFO - 'PWD': '/builds/slave/test', 13:48:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:51 INFO - 'SHELL': '/bin/bash', 13:48:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JfQcI5Fl1s/Listeners', 13:48:51 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 13:48:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:51 INFO - 'USER': 'cltbld', 13:48:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:51 INFO - 'XPC_FLAGS': '0x0', 13:48:51 INFO - 'XPC_SERVICE_NAME': '0', 13:48:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:51 INFO - Downloading/unpacking blobuploader==1.2.4 13:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:51 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 13:48:51 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 13:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:54 INFO - Downloading blobuploader-1.2.4.tar.gz 13:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:48:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:54 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 13:48:54 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 13:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:48:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:55 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 13:48:55 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 13:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:55 INFO - Downloading docopt-0.6.1.tar.gz 13:48:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:48:55 INFO - Installing collected packages: blobuploader, requests, docopt 13:48:55 INFO - Running setup.py install for blobuploader 13:48:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:48:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:48:56 INFO - Running setup.py install for requests 13:48:56 INFO - Running setup.py install for docopt 13:48:56 INFO - Successfully installed blobuploader requests docopt 13:48:56 INFO - Cleaning up... 13:48:56 INFO - Return code: 0 13:48:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:48: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')]} 13:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48: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')]} 13:48:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:56 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 0x10d7c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf3d8f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d59ed30>, '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 0x10d4c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d593be8>, '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.fF3883ew3A/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.JfQcI5Fl1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:56 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 13:48:56 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 13:48:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fF3883ew3A/Render', 13:48:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:56 INFO - 'HOME': '/Users/cltbld', 13:48:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:56 INFO - 'LOGNAME': 'cltbld', 13:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:56 INFO - 'MOZ_NO_REMOTE': '1', 13:48:56 INFO - 'NO_EM_RESTART': '1', 13:48:56 INFO - 'PAGER': '/bin/cat', 13:48:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:56 INFO - 'PWD': '/builds/slave/test', 13:48:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:56 INFO - 'SHELL': '/bin/bash', 13:48:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JfQcI5Fl1s/Listeners', 13:48:56 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 13:48:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:56 INFO - 'USER': 'cltbld', 13:48:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:56 INFO - 'XPC_FLAGS': '0x0', 13:48:56 INFO - 'XPC_SERVICE_NAME': '0', 13:48:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:48:57 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-E08k2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:48:57 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-D7fE7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:48:57 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-aqgW6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:48:57 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-tyCdpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:48:57 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-WrfTqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:48:57 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-vIP1j1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:48:57 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-WUSTUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:48:57 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-NGnOx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:48:57 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-rRXKNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:48:57 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-Ab4bx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:48:58 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-BfmeRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:48:58 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-X3N5sG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:48:58 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-1hyIBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:48:58 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-M6ehma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:48:58 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-8_rVvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:48:58 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-UJgWqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:48:58 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-oPRCER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:48:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:48:58 INFO - Running setup.py install for manifestparser 13:48:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:48:58 INFO - Running setup.py install for mozcrash 13:48:59 INFO - Running setup.py install for mozdebug 13:48:59 INFO - Running setup.py install for mozdevice 13:48:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:48:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:48:59 INFO - Running setup.py install for mozfile 13:48:59 INFO - Running setup.py install for mozhttpd 13:48:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:48:59 INFO - Running setup.py install for mozinfo 13:48:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:48:59 INFO - Running setup.py install for mozInstall 13:49:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:49:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:49:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:49:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:49:00 INFO - Running setup.py install for mozleak 13:49:00 INFO - Running setup.py install for mozlog 13:49:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:49:00 INFO - Running setup.py install for moznetwork 13:49:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:49:00 INFO - Running setup.py install for mozprocess 13:49:00 INFO - Running setup.py install for mozprofile 13:49:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:49:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:49:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:49:00 INFO - Running setup.py install for mozrunner 13:49:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:49:00 INFO - Running setup.py install for mozscreenshot 13:49:01 INFO - Running setup.py install for moztest 13:49:01 INFO - Running setup.py install for mozversion 13:49:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:49:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:49:01 INFO - Cleaning up... 13:49:01 INFO - Return code: 0 13:49:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:49:01 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')]} 13:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:49:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49:01 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')]} 13:49:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:49:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49:01 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 0x10d7c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf3d8f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d59ed30>, '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 0x10d4c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d593be8>, '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.fF3883ew3A/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.JfQcI5Fl1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:49:01 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 13:49:01 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 13:49:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fF3883ew3A/Render', 13:49:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:49:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:49:01 INFO - 'HOME': '/Users/cltbld', 13:49:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:49:01 INFO - 'LOGNAME': 'cltbld', 13:49:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:49:01 INFO - 'MOZ_NO_REMOTE': '1', 13:49:01 INFO - 'NO_EM_RESTART': '1', 13:49:01 INFO - 'PAGER': '/bin/cat', 13:49:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:49:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:49:01 INFO - 'PWD': '/builds/slave/test', 13:49:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:49:01 INFO - 'SHELL': '/bin/bash', 13:49:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JfQcI5Fl1s/Listeners', 13:49:01 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 13:49:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:49:01 INFO - 'USER': 'cltbld', 13:49:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:49:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:49:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:49:01 INFO - 'XPC_FLAGS': '0x0', 13:49:01 INFO - 'XPC_SERVICE_NAME': '0', 13:49:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:49:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:49:01 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-Z2HLDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:49:01 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-3mrA0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:49:01 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-1T175m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:49:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-KVxZTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:49:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-BoVXJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:49:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-sBvbgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:49:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-qF1Xsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:49:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-9oUW7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:49:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-krelyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:49:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-KBru8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:49:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-Keftth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:49:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-lq7kqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:49:03 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-EK24Rc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:49:03 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-gf7prS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:49:03 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-kV8the-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:49:03 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-yM40zA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:49:03 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-hLeXQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:49:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:49:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:49:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:49:03 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 13:49:03 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 13:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:49:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:49:06 INFO - Downloading blessings-1.6.tar.gz 13:49:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:49:06 INFO - Installing collected packages: blessings 13:49:06 INFO - Running setup.py install for blessings 13:49:06 INFO - Successfully installed blessings 13:49:06 INFO - Cleaning up... 13:49:06 INFO - Return code: 0 13:49:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:49:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:49:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:49:06 INFO - Reading from file tmpfile_stdout 13:49:06 INFO - Current package versions: 13:49:06 INFO - blessings == 1.6 13:49:06 INFO - blobuploader == 1.2.4 13:49:06 INFO - docopt == 0.6.1 13:49:06 INFO - functools32 == 3.2.3-2 13:49:06 INFO - jsonschema == 2.5.1 13:49:06 INFO - manifestparser == 1.1 13:49:06 INFO - mozInstall == 1.12 13:49:06 INFO - mozcrash == 1.0 13:49:06 INFO - mozdebug == 0.1 13:49:06 INFO - mozdevice == 0.49 13:49:06 INFO - mozfile == 1.2 13:49:06 INFO - mozhttpd == 0.7 13:49:06 INFO - mozinfo == 0.9 13:49:06 INFO - mozleak == 0.1 13:49:06 INFO - mozlog == 3.4 13:49:06 INFO - moznetwork == 0.27 13:49:06 INFO - mozprocess == 0.24 13:49:06 INFO - mozprofile == 0.28 13:49:06 INFO - mozrunner == 6.13 13:49:06 INFO - mozscreenshot == 0.1 13:49:06 INFO - mozsystemmonitor == 0.3 13:49:06 INFO - moztest == 0.8 13:49:06 INFO - mozversion == 1.4 13:49:06 INFO - psutil == 3.1.1 13:49:06 INFO - requests == 1.2.3 13:49:06 INFO - wsgiref == 0.1.2 13:49:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49:07 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')]} 13:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49:07 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 0x10d7c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf3d8f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d59ed30>, '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 0x10d4c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d593be8>, '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.fF3883ew3A/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.JfQcI5Fl1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:49:07 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 13:49:07 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 13:49:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fF3883ew3A/Render', 13:49:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:49:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:49:07 INFO - 'HOME': '/Users/cltbld', 13:49:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:49:07 INFO - 'LOGNAME': 'cltbld', 13:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:49:07 INFO - 'MOZ_NO_REMOTE': '1', 13:49:07 INFO - 'NO_EM_RESTART': '1', 13:49:07 INFO - 'PAGER': '/bin/cat', 13:49:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:49:07 INFO - 'PWD': '/builds/slave/test', 13:49:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:49:07 INFO - 'SHELL': '/bin/bash', 13:49:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JfQcI5Fl1s/Listeners', 13:49:07 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 13:49:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:49:07 INFO - 'USER': 'cltbld', 13:49:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:49:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:49:07 INFO - 'XPC_FLAGS': '0x0', 13:49:07 INFO - 'XPC_SERVICE_NAME': '0', 13:49:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:49:07 INFO - Cleaning up... 13:49:07 INFO - Return code: 0 13:49:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:49:07 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')]} 13:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49:07 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')]} 13:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10d7c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaf3d8f100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d59ed30>, '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 0x10d4c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d593be8>, '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.fF3883ew3A/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.JfQcI5Fl1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:49:08 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 13:49:08 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 13:49:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fF3883ew3A/Render', 13:49:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:49:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:49:08 INFO - 'HOME': '/Users/cltbld', 13:49:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:49:08 INFO - 'LOGNAME': 'cltbld', 13:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:49:08 INFO - 'MOZ_NO_REMOTE': '1', 13:49:08 INFO - 'NO_EM_RESTART': '1', 13:49:08 INFO - 'PAGER': '/bin/cat', 13:49:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:49:08 INFO - 'PWD': '/builds/slave/test', 13:49:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:49:08 INFO - 'SHELL': '/bin/bash', 13:49:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JfQcI5Fl1s/Listeners', 13:49:08 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 13:49:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:49:08 INFO - 'USER': 'cltbld', 13:49:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:49:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:49:08 INFO - 'XPC_FLAGS': '0x0', 13:49:08 INFO - 'XPC_SERVICE_NAME': '0', 13:49:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:49:08 INFO - Cleaning up... 13:49:08 INFO - Return code: 0 13:49:08 INFO - Running post-action listener: _resource_record_post_action 13:49:08 INFO - Running post-action listener: _start_resource_monitoring 13:49:08 INFO - Starting resource monitoring. 13:49:08 INFO - [mozharness: 2017-02-10 21:49:08.539270Z] Finished create-virtualenv step (success) 13:49:08 INFO - [mozharness: 2017-02-10 21:49:08.540207Z] Running install step. 13:49:08 INFO - Running pre-action listener: _resource_record_pre_action 13:49:08 INFO - Running main action method: install 13:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:49:08 INFO - Reading from file tmpfile_stdout 13:49:08 INFO - Detecting whether we're running mozinstall >=1.0... 13:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:49:08 INFO - Reading from file tmpfile_stdout 13:49:08 INFO - Output received: 13:49:08 INFO - Usage: mozinstall [options] installer 13:49:08 INFO - Options: 13:49:08 INFO - -h, --help show this help message and exit 13:49:08 INFO - -d DEST, --destination=DEST 13:49:08 INFO - Directory to install application into. [default: 13:49:08 INFO - "/builds/slave/test"] 13:49:08 INFO - --app=APP Application being installed. [default: firefox] 13:49:08 INFO - mkdir: /builds/slave/test/build/application 13:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:49:30 INFO - Reading from file tmpfile_stdout 13:49:30 INFO - Output received: 13:49:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:49:30 INFO - Running post-action listener: _resource_record_post_action 13:49:30 INFO - [mozharness: 2017-02-10 21:49:30.846280Z] Finished install step (success) 13:49:30 INFO - [mozharness: 2017-02-10 21:49:30.846446Z] Running run-tests step. 13:49:30 INFO - Running pre-action listener: _resource_record_pre_action 13:49:30 INFO - Running main action method: run_tests 13:49:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:49:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:49:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:49:30 INFO - 2017-02-10 13:49:30.861 screenresolution[1625:7354] starting screenresolution argv=screenresolution get 13:49:30 INFO - 2017-02-10 13:49:30.864 screenresolution[1625:7354] Display 0: 1600x1200x32@60 13:49:30 INFO - 2017-02-10 13:49:30.870 screenresolution[1626:7356] starting screenresolution argv=screenresolution list 13:49:30 INFO - Available Modes on Display 0 13:49:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:49:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:49:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:49:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:49:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:49:31 INFO - Intel Iris: 13:49:31 INFO - Chipset Model: Intel Iris 13:49:31 INFO - Type: GPU 13:49:31 INFO - Bus: Built-In 13:49:31 INFO - VRAM (Dynamic, Max): 1536 MB 13:49:31 INFO - Vendor: Intel (0x8086) 13:49:31 INFO - Device ID: 0x0a2e 13:49:31 INFO - Revision ID: 0x0009 13:49:31 INFO - Displays: 13:49:31 INFO - Display: 13:49:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:49:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:49:31 INFO - Display Serial Number: 1600x1200 60 13:49:31 INFO - Main Display: Yes 13:49:31 INFO - Mirror: Off 13:49:31 INFO - Online: Yes 13:49:31 INFO - Rotation: Supported 13:49:31 INFO - Return code: 0 13:49:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:49:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:49:31 INFO - Python 2.7.3 13:49:31 INFO - Return code: 0 13:49:31 INFO - grabbing minidump binary from tooltool 13:49:31 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')]} 13:49:31 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 0x10d59ed30>, '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 0x10d4c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d593be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:49:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:49:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 13:49:31 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 13:49:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:49:31 INFO - Return code: 0 13:49:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:49:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:49:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:49:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:49:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:49:31 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/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0087', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 13:49:31 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/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0087 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 13:49:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fF3883ew3A/Render', 13:49:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:49:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:49:31 INFO - 'HOME': '/Users/cltbld', 13:49:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:49:31 INFO - 'LOGNAME': 'cltbld', 13:49:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:49:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:49:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:49:31 INFO - 'MOZ_NO_REMOTE': '1', 13:49:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:49:31 INFO - 'NO_EM_RESTART': '1', 13:49:31 INFO - 'PAGER': '/bin/cat', 13:49:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:49:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:49:31 INFO - 'PWD': '/builds/slave/test', 13:49:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:49:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:49:31 INFO - 'SHELL': '/bin/bash', 13:49:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JfQcI5Fl1s/Listeners', 13:49:31 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 13:49:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:49:31 INFO - 'USER': 'cltbld', 13:49:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:49:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:49:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:49:31 INFO - 'XPC_FLAGS': '0x0', 13:49:31 INFO - 'XPC_SERVICE_NAME': '0', 13:49:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:49:31 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/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0087', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 13:49:31 INFO - mozversion application_buildid: 20170210123623 13:49:31 INFO - mozversion application_changeset: a288fe35e494cec3620717eab66eaff6f68a6369 13:49:31 INFO - mozversion application_display_name: Nightly 13:49:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:49:31 INFO - mozversion application_name: Firefox 13:49:31 INFO - mozversion application_remotingname: firefox 13:49:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:49:31 INFO - mozversion application_vendor: Mozilla 13:49:31 INFO - mozversion application_version: 54.0a1 13:49:31 INFO - mozversion platform_buildid: 20170210123623 13:49:31 INFO - mozversion platform_changeset: a288fe35e494cec3620717eab66eaff6f68a6369 13:49:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:49:31 INFO - mozversion platform_version: 54.0a1 13:49:31 INFO - using testdate: 1486763371 13:49:31 INFO - actual date: 1486763371 13:49:31 INFO - starting webserver on 'localhost:49244' 13:49:31 INFO - SUITE-START | Running 2 tests 13:49:31 INFO - TEST-START | dromaeo_css 13:49:31 INFO - operating with platform_type : mac_ 13:49:31 INFO - Initialising browser for dromaeo_css test... 13:49:31 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmp2QpNoy/profile http://localhost:49244/getInfo.html) 13:49:34 INFO - PROCESS | 1636 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 13:49:34 INFO - PROCESS | 1636 | 2017-02-10 13:49:34.584 plugin-container[1638:7524] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b43, name = 'com.apple.tsm.portname' 13:49:34 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:49:34 INFO - PROCESS | 1636 | 2017-02-10 13:49:34.588 plugin-container[1638:7524] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 13:49:34 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:49:34 INFO - PROCESS | 1636 | 2017-02-10 13:49:34.589 plugin-container[1638:7524] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:49:34 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 13:49:34 INFO - PROCESS | 1636 | colorDepth:24 13:49:34 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 13:49:34 INFO - PROCESS | 1636 | __metrics 13:49:34 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 13:49:35 INFO - TEST-INFO | 1636: exit 0 13:49:35 INFO - Browser initialized. 13:49:35 INFO - Running cycle 1/1 for dromaeo_css test... 13:49:35 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmp2QpNoy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:49:37 INFO - PROCESS | 1639 | 2017-02-10 13:49:37.941 plugin-container[1640:7647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 13:49:37 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:49:37 INFO - PROCESS | 1639 | 2017-02-10 13:49:37.941 plugin-container[1640:7647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 13:49:37 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:49:37 INFO - PROCESS | 1639 | 2017-02-10 13:49:37.941 plugin-container[1640:7647] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:49:47 INFO - PROCESS | 1639 | RSS: Main: 233787392 13:49:47 INFO - PROCESS | 1639 | 13:52:49 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 13:52:51 INFO - PROCESS | 1639 | RSS: Main: 258953216 13:52:51 INFO - PROCESS | 1639 | 13:54:35 INFO - PROCESS | 1639 | ************************* 13:54:35 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 13:54:35 INFO - PROCESS | 1639 | 13:54:35 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:54:35 INFO - PROCESS | 1639 | Full stack: 13:54:35 INFO - PROCESS | 1639 | ************************* 13:55:53 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 13:55:54 INFO - PROCESS | 1639 | RSS: Main: 268247040 13:55:54 INFO - PROCESS | 1639 | 13:58:56 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 13:58:56 INFO - PROCESS | 1639 | RSS: Main: 279080960 13:58:56 INFO - PROCESS | 1639 | 14:01:58 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 14:01:59 INFO - PROCESS | 1639 | RSS: Main: 272441344 14:01:59 INFO - PROCESS | 1639 | 14:05:02 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 14:05:03 INFO - PROCESS | 1639 | RSS: Main: 277389312 14:05:03 INFO - PROCESS | 1639 | 14:08:05 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 14:08:05 INFO - PROCESS | 1639 | RSS: Main: 279244800 14:08:05 INFO - PROCESS | 1639 | 14:08:05 INFO - PROCESS | 1639 | __start_tp_report 14:08:05 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 14:08:05 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 14:08:05 INFO - PROCESS | 1639 | |i|pagename|runs| 14:08:05 INFO - PROCESS | 1639 | |0;dojo.html;3310.2386451116226;3322.5514555534996;3334.116459138328;3339.0817525180573;3358.9251439539344;4930.926036109461;5415.593830462722;5514.558835293177;5553.833384998451;5573.164025396192;3890.941635875457;5370.140777475604;5442.863928401792;5446.7276636168135;5474.863128421792;4284.657227421799;4566.223741963858;5021.47274536173;5048.444671086178;5097.158968770167;5049.116404629175;5148.2535032420465;5151.74241287936;5194.870128246786;5214.478552144774;5167.431582525919;5181.404138524062;5187.27378167055;5188.9362680650565;5233.110371236899;601.8981206651684;5073.797048118071;5078.5175408336145;5118.206677964921;5166.612504062192;4604.894825241951;4694.741789201593;5122.359705036871;5156.587473002151;5158.1746920492915;4828.61371090312;5364.973175134139;5431.212474191259;5439.9936011837935;5457.208704737831;5851.853703657443;5924.348321684612;6095.2685677718955;6123.326434092246;6188.84527886802;6107.7957376609465;6142.161149281699;6171.13604095427;6227.19046523949;6229.3770622937345;5745.68398738065;5749.540036797046;5757.222774925415;5766.509846662997;5769.740361683735;4796.472388037313;5024.422191447957;6086.969565152234;6283.7800676976085;6289.811305660837;5814.156947242626;5958.612690175125;5976.760929562769;6024;6104.359042300494;5085.949140508547;6100.908486372708;6129.1419201311855;6135.631862088289;6172.814815555541;5783.16144159103;5906.970465147732;5992.67040312787;6012.849678757979;6014.789482368096;6087.847803804941;6099.420555047263;6109.572329936951;6134.601250918676;6138.508919286446;4867.683600565952;4870.196417591129;4871;4877.073356062337;4906.239532872411;4294.184105020098;4297.290946993773;4347.782610869507;4366.366876802846;4385;5637.154426835926;5670.376258611549;5698.914516282259;5700.486956173964;5713.628614140068;6072.36240194782;6106.572539922252;6135.570510064342;6161.84595385119;6183.310260036898;4865.759231395971;5446.618736688473;5574.972125139429;5663.631863928831;5668.064769313017;5269.4993975573025;5314.256004159421;5316.0962636351915;5319.893602127936;5385.919211211835;6231.9688401557705;6266.27937787142;6276.905846412307;6280.057991301342;6292.811215663537;6071.574989750675;6163.921313769983;6176.505879529537;6198.6280823150755;6199.28708198563;6113;6117.766507567632;6168.475679567107;6184.5980011299125;6267.091271765477;6111.152629350003;6158.0147176451555;6163.537734670007;6167.167432396569;6168.1056246844855;5996.760129594767;6203.689815509297;6209.59637623553;6221.595596286408;6245.812625621239;4841.176999909953;5755.280589926259;5895.380984996514;5911.379305173067;5991;5216.061109000417;5676.886462270649;5961.701914904324;5998.280206375263;6005;4846.733429661403;5712.057510510719;5792.9710351447975;5814.418558144152;5839.299284085937;5768.50967667753;5867.647941123546;5889.823305300676;5921.733521991435;5984.700764961647;5758.712064396847;5769.79805706815;5771.422857714195;5787.710614469175;5792.797252096157 14:08:05 INFO - PROCESS | 1639 | |1;ext.html;449.93700881876543;482.8117034356601;505.0177404947379;510.74357081579313;519.3352508788751;27207.5918861217;30262.69737302626;38740.80629596852;40396.19207615846;40415;40860.59139408605;41246.175076498526;41339.37990930131;41347;41405.37891931624;9120.8631870522;9275.860862087075;9295.163435290822;9298.16316531512;9326;7814.413918956073;8010.67957281708;9239.39943903644;10805;11096.667099987013;10952.616658416957;10987.945060274727;11002.889971100307;11054.392008439532;11057.778844423106;8400.781886626435;9473.478958657272;9771.12059914611;10398;11046.392448415334;10813.134949204044;10899.346039237591;10901.672949811518;10909.18181136418;10943;38633.03417414559;38698.13018698136;39009.829705108896;39045.41431878524;39194.60805391938;3818.1409182933894;3848.5189351331082;3862.497875276224;3870.825812838429;3957.6240257175523;28081.578776318373;29207.707922920923;29237.122886313446;29321.48142222947;29372.97194598179;3044.9543256851166;3112.128603990886;3207.5188721691698;3243.5296881951986;3247.3342964692183;340162.2991885049;342373;342583;342793;342947;58138.4186158133;58611;58626;58639.70680146572;58680.41319586835;54904.450955489934;55276.17085743717;61540.69229653823;62847;62854;12664.556740514037;14727.55817325482;14903.925480372745;14923.253837308308;14950.626234344229;11490.655280341603;11583.6524904253;11598.18812683121;11603.6518904433;11606.24559403636;16141.677166456606;16142.838571614368;16234.675306493804;16238.538531532218;16245.593860153354;6587.80236592903;6598.538102332791;6634.0712300278;6663.167104111986;6668.199816022108;802.7872613757359;816.8897198878194;817.1134319263547;843.3211264931666;848.5460278750838;28254.434911301658;29300;29427.558586621217;29698.54521821744;30123.548146777815;983.4296108257194;1007.3804610164842;1022.3508072373947;1050.5535147562255;1169.8771628979005;15272.16003119817;15275.465358712392;15325.23373831304;15358.923205384124;15414.84585154134;561348;562436;565136;566719;568885.4891608661;57115.428845711845;57987.71006144942;58097.41902581005;58258.92187546831;58316.12525812116;1312.4618906248393;1354.4243696429176;1354.5056054540023;1360.5510181640036;1366.631009627395;27789.305267368476;28720.712792871804;28849.711502884704;28867.8556607224;29326.853365733034;48661.02677946351;49025.754871225414;49030;49043.77390565264;49165.508344916096;504083.39791505504;508239;508293;508958;509466;24335.391615209053;24923.875380623707;25006.374840628403;25182;38319.467221310835;40784.98037549085;41110.794446027576;41321.380179296735;41740.1651966965;41906.74279771612;33145;33887.322253555285;34368.48447273343;35337.293254135286;35543.64456355404;33849.49225761566;34223.4866477008;34887.953361399195;34969.475457867644;35461.82269088638;33866;34327.65672343245;34479.13802154966;34593.827030865694;34691.4796278051;29620;30734.846325768227;30831.845840770653;31104.377912442076;31207.687923120477;22301.330960071224;22401.983821456146;29446.705532945252;31967.520487192247;33006.00981970545 14:08:05 INFO - PROCESS | 1639 | |2;jquery.html;363.9290338384016;385.8309322752059;394.73947194851394;397.4832717467292;418.61069205638745;17196.570085747862;18698.813011869875;20227.089780959854;21011.894940525275;21026;20608.072636731347;20995.370138895818;21106.15575376983;21375.786242137576;21405.892970535126;4553.68124231304;5095.71973541456;5786.08539627142;5881.8823623527505;5933.13969474426;5786.855328616776;5840.883182336351;5901.881962360751;5911.349751527328;5915.940840591604;5861.677607731573;6154.138420621117;6493.577917435376;6555.180602424697;6591.769288074918;6360.14138091361;6526.706298216568;6541.47668186544;6558.114654521626;6617.007448882682;5729.398413166641;5873.500752436048;5880.441358070976;5900.498457631106;5907.793227237026;12674.302913339854;17690.91154544219;18112.637747244982;24771.876140619363;24960;2564.4101856573093;2566.8203225774205;2570.5630042892753;2643.246674697721;2840.1930653862046;9853;10179.541920613516;10214.489275536194;10276.434796086212;12749.788770066829;1569.0115227406661;1664.7253203221576;1697.889637173592;1765.9028753418556;1824.3158815444208;110435;115126;117791;139754.30122849322;140171.29914350464;11582.94208528952;11848.229865058744;11944.104192185621;11980.221285616408;12075.879241207476;15434.15112168819;15640.139792311533;15669.608259793597;15678.608034799221;15746.606334841721;8081.232282933112;8349.290310323691;8350.415470916978;8358.665653373797;8387.329013679013;6666.83332916681;7972.441929064909;7977.960110199412;8105.594720264081;8112.02655681322;4769.594584460359;4799.328094066834;4859.441164266084;4860.854174374775;4954.232094025432;1813.8820976636475;1998.5103649605649;2033.501792060925;2074.6991686205415;2079.55289612734;985;996.3944285692861;1006.1296978113879;1009.7021378693156;1049.7113293844254;20602.690959635616;21125.154993800385;21470.892645536984;21615.35153945384;21622.78377216239;1008.2740426892625;1011.110223003752;1039.5633833789827;1043.2749239278628;1045.1012129568564;10846.349219046882;11539.76920461586;11569.247998880046;11579.76840463186;11600.187986841009;576939;608569;621437;659158.6757191779;660687;127556.36221818831;132786;135618;139107.60892390946;148801;984.17329443268;994.8360418310594;1019.0166489337824;1044.5612842605972;1050.2963014779964;14792;14849.703005940037;19176.52058698488;19246.326378576683;19879.503012424804;55211;58138;58322.54193645023;58668;59097;68026.97959530512;83537;84772.57613711891;85720;86377.56811215903;6193.628382296896;8173.632186551738;8316.875246871423;8471.32229421657;8473.703420380258;8019.238172373615;12419.19275247106;12577.245365278113;12680.048996325497;12917.870821291666;10816.296940698829;11170.832437513607;11216.102711783202;11227.438628068727;11396.544138234378;8104.230098140667;8789.648414063366;10124.13944814699;10850.131989440984;11168.385738784445;8964.865527017231;9117.042710515301;10490.423026733602;11222.438878056228;11249.606263780728;8015.398845086525;8066.919330806617;8135.552544610104;8423.284020858297;8441.70454034106;9834.754131146492;10804.40575768309;11092.725991171628;11099.611513596985;11109.166812489257 14:08:05 INFO - PROCESS | 1639 | |3;mootools.html;1248.3882897380283;1408.9084209526375;1413.8303403591572;1430.2634143416142;1432.412710788577;8031.83936321274;8190.2628763411285;8428.747137585882;8493.235608795216;8513.276371508422;8345.123762004992;8482.236598706115;8494.575271236445;8506;8514.95742521288;2228.621134407155;2259.3109101723967;2280.851744636602;2285.874276914769;2286.7255929288426;1819.6633622779827;2246.2025980776834;2275.4538910661486;2285.4514916420108;2293.4381076636164;1755.9464321407117;2050.682144267641;2207.5033117548583;2262.1969200933677;2266.195500597281;2191.931222766277;2244.2033078257227;2268.5236100418933;2274.3972847195505;2276.4536511237347;1940.2045161483866;2033.8779673219653;2232.4195709115747;2300.976990230093;2311.2719493359537;8250.669973201066;8379.413441059129;8445.831083378298;8478.66085356585;8580.056193818675;5178.326817513736;5233.790648374061;5239.921401178985;5243.9213411798855;5254.185601231815;2847.2454799478155;3416.692497675196;3440.501127336547;3498.9650103498893;3537.7169826413824;1192.6004788395899;1194.7132688154868;1226.5400474821943;1232.3961258983124;1252.843394575678;10805.945970270177;10892.836607450894;10943;10973.561057557688;11007.284526505751;4182.456280683492;4431.534688857688;4501.167284052461;4507.797149128297;4517.6160026397465;8616.698415555426;8745.081766414434;8746.387752857367;8748.8687669685;8755.649774009096;5095.31213286209;5095.974520127375;5097.6941383517105;5102.617303702237;5116.462771409023;4513.368128462018;4553.4991150973365;4665.430945326828;4674.462436819741;4727.338172655831;3585.1216451969435;3619.077135330474;3651.940937128203;3682.3924052531033;3717.7955212463576;1078.795028944498;1134.0927258193412;1141.8230174322994;1142.331735934471;1146.3866831245243;721.7582109993211;736.3630459652362;752.2139364364143;754.5738554132694;777.9144294127641;5244.632875698664;5562.610617256834;5611.80358687444;5684.971575142098;5699.373068962494;481.7398604753394;488.7116601205306;540.106124364178;545.5035917315224;578.5198285423173;3589.766665166756;3638.1450359166106;3647.62834432341;3702.9259414811554;3705.6850167736043;9709.854352184722;10043.146332561813;10111;10123.341982771244;10162.441065741308;9770.853437198448;10070.89220185779;10093.343932644355;10096.646617368504;10104.595816167419;7774.339181169664;7888.211178882066;7891.565963871927;7901.60491975399;7930.484518506279;4858.562729354234;5602.187682785891;5609;5640.520555752807;5887.0580707086665;7566.924330756842;8994.370394072352;9273.814523709352;9284.210842078499;9303.441793492142;8961.641534338554;9154.038825923182;9222.170004699608;9327.486988215713;9340.065993400878;7590.582517961565;7783.104942931635;7790.688372465038;7829.530228186327;7847.0975837777105;7699.615019248903;7876.173001834725;7886.171951944963;7889.2110788920645;7914.960425197837;6050;7063.5055546113335;7122.358987691133;7191.280871912767;7215.422766178588;6899.931000689929;7000.61496617691;7003.789886303419;7036.824079398056;7051.153861536648;6212.937870621236;6371.808845734636;6408.551401401857;6449.774257900951;6476.935230647821;4992.825251116194;5036.06832735945;5061.848144555669;5093.8726531837;5099.821506247264;4968.975155124202;5039.748012599429;5051.823186188466;5222.9216561750845;5269.525742683177 14:08:05 INFO - PROCESS | 1639 | |4;prototype.html;298.6998066942723;352.513531326769;368.15141099765066;370.69602925601004;372.9608391118934;4179.289520781467;5038.924416133761;5165.354330708667;5287.629865909388;5366.275552800371;4310.922269432642;4596.0578081493295;5296.470352964701;5297.655922143834;5347.59893008024;4186.267403204427;4186.2883309837325;4226.429432026668;4226.682998775089;4228.873133805975;3702.685271751891;4226.408302837605;4226.45056142701;4250.426192464024;4261.680373971949;3923.274194274054;3941.9211615767667;3954.2882281189372;3962.5839286874893;3972.940405893914;3931.9017024574327;3943.6253555912285;3951.9844879111574;3971.7290467050557;4001.379786133154;3010.1722026442685;3417.79493230407;3419.401604719189;3420.452727563603;3426.400379933502;4708.57622813945;4826.22780355145;5142.665726727788;5184.6629969052265;5202.193659982709;4581.770911454414;4603.746793926332;4612.262038073925;4613.031263434683;4677;4830.855074347775;5652.943470565324;5678.630888992202;5696.287964004499;5737.2254745609225;3054.7861649684532;3545.663161999606;3595.352836489431;3609.8375573099274;3718.0704823794053;4562.817487300504;5597.984725498817;5843.298804143487;5902.734376953004;5961.850953726148;3422.281320922634;3773.3773927302236;3868.9226215475533;3899.810557779848;3904.5509766376663;3909.315869722844;3951.980240098781;3953.090789118519;3979.164375481181;3979.8607048753156;3656.469811877263;3941.5664276929515;4463.4420697412825;4594.104149690778;4642.651801114863;4456.3538286948215;5223.503767142115;5340.839774806762;5397.8380648580605;5493.725313734298;1133.5749094089717;1181.6041626055369;1208.1361826294242;1256.8051951947464;1281.2248589603341;777.9338756205673;806.9031716194095;813.7111325479488;863.6027427383349;877.4998250996871;4355.586219309161;4622.913615300402;5022.221555658879;5027.271045698353;5038.7073881383885;4777.4505931818285;4803.55018940343;5294.338207724035;5430.185472179126;5572.804951826666;4270.380794784739;4643.257078867365;4802.663813533089;5040.991801639613;5049.394072711225;2503.2865633294787;2644;2911.7379435850876;2941.573471846613;2946.499095153829;5363.927214557026;5573.854066184548;5777.19119323295;5788.710564471759;5808.361080281165;5354.919676204782;5427.972860135674;5551.944480555224;5737.598368114275;6028.849278767978;5082.567981721595;5540.63985840919;6036.758529658764;6099.969500152471;6104.542159338067;4436.223660859401;5652.6043176978865;5724.284464441945;5871.500922421642;5909.661041511532;5510;5796.913046304225;5828.708564571839;5833.366657335952;5841.912371314519;5750.7987220447085;5877.706114694333;5983.222181116427;6101.725422355916;6103.6032657877095;3821.86606982283;3847.6729477994686;3892.9829382227867;3953.9802300988313;3966.186931678941;4450.065486247924;4554.453465584019;5385.865353366197;5890.558208134321;5893.557983151367;4666.200279832074;5393.919091213556;5414.377346605191;5473.124300111963;5665.603407761417;5168.58651307902;5194.584433245256;5344.545713614386;5491.972540137273;5692.5445964321925;5150.510701483353;5550.391316081435;5556.444355564411;5596.776128954796;5637.943620563742;5265.947340526545;5410.918836217538;5633.7324351874995;5734.569907256889;5737.770489180386;4917.164082105979;4933.605311575137;5613.578981576316;5619.438056194348;5657.264555607745 14:08:05 INFO - PROCESS | 1639 | |5;yui.html;696.7491702986924;698.3993765361785;703.3248081841433;774.9770303199775;800.5076877720202;14210.147391156524;14826.184559849204;14891.478798242064;15096.0187587807;15311.004784688987;13505;13620.72758544831;13720.862791372083;13737;13929.860701392983;659.1694464974144;671.5064427645701;683.7982795075452;697.2225968045643;698.2703075286338;663.7477758451806;666.9662023863017;684.1550684904139;706.9505134640551;712.0991945189339;704.5702121705756;705.9364657180853;730.817295676081;740.9222031686676;742.0353540397489;713.8179764160158;731.8463122744257;738.1290077708302;740.6185814305637;747.4319517166938;511.96071973893055;524.6615932723372;532.6564365983958;533.8478533617939;546.0662267522541;14590.854091459056;14838.258087095603;14883.70232595342;14941.402343906231;15070.698586028218;13545;13576.309340201313;13612.795808062887;13749.793753093712;13798.65503362424;10891.237613367068;11373.772524549546;11460.828087578693;11479.540818367255;11644.41777911101;3395.134240768613;3478.5999610044914;3603.6396360364015;3607.6753092221825;3738.7195960302815;101682.47476287861;102337;103018.48490757498;103234.48382758113;103498.41253968635;10774;10775.191860610328;10785.13718902486;10887.83668244977;11037.503312350964;21010.15959361639;21429.249976251067;21452.249171279243;21570.676439853414;21761.67357489607;6463.741450341934;6926.515143939977;7524.548527088392;7616.466847320635;7700.268474494914;4011.498562679665;4087.1338339881986;4139.068709540389;4163.521195062546;4191.664666826647;3224.6775322467565;3588.1567831559564;3605.639436056426;3611.5485564304463;3617.2403795203304;654.1202083198091;664.764008776887;669.0866966590667;682.5085938124543;686.9450443964471;563.4872266237704;581.5841673203595;653.650297091054;681.4139839737821;701.6916134931524;8477.830443391098;9767.51162441875;9970.800583988279;10148.179614683115;10190.031946965026;582.8105865593699;661.7551505942832;681.151965802579;687.412262515552;695.994288253479;4649.767511624405;4805.7597120143855;4867.466747974377;4889.193283108319;4917.803287868517;74350.6282468595;92568;97338;97799;100318;57922.7103864478;65542.34457655363;72645.63677181686;83605.16394836096;86116.70824937632;56078;62270;63650.681746591894;72394.63802680952;72415;9909.801803963735;10596.258261921592;10734.980176883184;10782.08352290064;10849.67450976472;36129.81935090413;46435.767821160676;47164;48020.51979480161;48489;65953;66776;67907;68071.97892031525;68774;12314.760371924385;12549.121561490609;12656.6835829105;12716.872831271568;13081;11974.521019159136;12005.219660722025;12022.819657704966;12045.33750643723;12088.818667720167;10991.61529346469;12497.25016499013;13081.607551773463;13154.802677959648;13422.932885335511;13082.084254102121;13200.207987520394;13388.866111338762;13446.394912228778;13562.93218533901;12654.240745555297;12802.74394512086;13247.80128298057;13327.400711914632;13581.32093395346;10248.487575621339;10629.89370106289;10826.458677066274;10980.945095274472;11406.54373825071;8323.959505061866;9759.75600609962;11308.151888608223;11424.314541127558;11586.594469193538 14:08:05 INFO - PROCESS | 1639 | __end_tp_report 14:08:05 INFO - PROCESS | 1639 | __start_cc_report 14:08:05 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,409 14:08:05 INFO - PROCESS | 1639 | __end_cc_report 14:08:05 INFO - PROCESS | 1639 | __startTimestamp1486764485782__endTimestamp 14:08:05 INFO - PROCESS | 1639 | 14:08:05 INFO - PROCESS | 1639 | ------- Summary: start ------- 14:08:05 INFO - PROCESS | 1639 | Number of tests: 6 14:08:05 INFO - PROCESS | 1639 | 14:08:05 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5531.14 Median:5766.51 stddev:768.09 (13.3%) stddev-sans-first:750.43 14:08:05 INFO - PROCESS | 1639 | Values: 3310.2 3322.6 3334.1 3339.1 3358.9 4930.9 5415.6 5514.6 5553.8 5573.2 3890.9 5370.1 5442.9 5446.7 5474.9 4284.7 4566.2 5021.5 5048.4 5097.2 5049.1 5148.3 5151.7 5194.9 5214.5 5167.4 5181.4 5187.3 5188.9 5233.1 601.9 5073.8 5078.5 5118.2 5166.6 4604.9 4694.7 5122.4 5156.6 5158.2 4828.6 5365.0 5431.2 5440.0 5457.2 5851.9 5924.3 6095.3 6123.3 6188.8 6107.8 6142.2 6171.1 6227.2 6229.4 5745.7 5749.5 5757.2 5766.5 5769.7 4796.5 5024.4 6087.0 6283.8 6289.8 5814.2 5958.6 5976.8 6024.0 6104.4 5085.9 6100.9 6129.1 6135.6 6172.8 5783.2 5907.0 5992.7 6012.8 6014.8 6087.8 6099.4 6109.6 6134.6 6138.5 4867.7 4870.2 4871.0 4877.1 4906.2 4294.2 4297.3 4347.8 4366.4 4385.0 5637.2 5670.4 5698.9 5700.5 5713.6 6072.4 6106.6 6135.6 6161.8 6183.3 4865.8 5446.6 5575.0 5663.6 5668.1 5269.5 5314.3 5316.1 5319.9 5385.9 6232.0 6266.3 6276.9 6280.1 6292.8 6071.6 6163.9 6176.5 6198.6 6199.3 6113.0 6117.8 6168.5 6184.6 6267.1 6111.2 6158.0 6163.5 6167.2 6168.1 5996.8 6203.7 6209.6 6221.6 6245.8 4841.2 5755.3 5895.4 5911.4 5991.0 5216.1 5676.9 5961.7 5998.3 6005.0 4846.7 5712.1 5793.0 5814.4 5839.3 5768.5 5867.6 5889.8 5921.7 5984.7 5758.7 5769.8 5771.4 5787.7 5792.8 14:08:05 INFO - PROCESS | 1639 | 14:08:05 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:61078.05 Median:34423.81 stddev:128244.20 (372.5%) stddev-sans-first:128523.17 14:08:05 INFO - PROCESS | 1639 | Values: 449.9 482.8 505.0 510.7 519.3 27207.6 30262.7 38740.8 40396.2 40415.0 40860.6 41246.2 41339.4 41347.0 41405.4 9120.9 9275.9 9295.2 9298.2 9326.0 7814.4 8010.7 9239.4 10805.0 11096.7 10952.6 10987.9 11002.9 11054.4 11057.8 8400.8 9473.5 9771.1 10398.0 11046.4 10813.1 10899.3 10901.7 10909.2 10943.0 38633.0 38698.1 39009.8 39045.4 39194.6 3818.1 3848.5 3862.5 3870.8 3957.6 28081.6 29207.7 29237.1 29321.5 29373.0 3045.0 3112.1 3207.5 3243.5 3247.3 340162.3 342373.0 342583.0 342793.0 342947.0 58138.4 58611.0 58626.0 58639.7 58680.4 54904.5 55276.2 61540.7 62847.0 62854.0 12664.6 14727.6 14903.9 14923.3 14950.6 11490.7 11583.7 11598.2 11603.7 11606.2 16141.7 16142.8 16234.7 16238.5 16245.6 6587.8 6598.5 6634.1 6663.2 6668.2 802.8 816.9 817.1 843.3 848.5 28254.4 29300.0 29427.6 29698.5 30123.5 983.4 1007.4 1022.4 1050.6 1169.9 15272.2 15275.5 15325.2 15358.9 15414.8 561348.0 562436.0 565136.0 566719.0 568885.5 57115.4 57987.7 58097.4 58258.9 58316.1 1312.5 1354.4 1354.5 1360.6 1366.6 27789.3 28720.7 28849.7 28867.9 29326.9 48661.0 49025.8 49030.0 49043.8 49165.5 504083.4 508239.0 508293.0 508958.0 509466.0 24335.4 24923.9 25006.4 25182.0 38319.5 40785.0 41110.8 41321.4 41740.2 41906.7 33145.0 33887.3 34368.5 35337.3 35543.6 33849.5 34223.5 34888.0 34969.5 35461.8 33866.0 34327.7 34479.1 34593.8 34691.5 29620.0 30734.8 30831.8 31104.4 31207.7 22301.3 22402.0 29446.7 31967.5 33006.0 14:08:05 INFO - PROCESS | 1639 | 14:08:05 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:36271.96 Median:21619.07 stddev:104768.22 (484.6%) stddev-sans-first:105027.43 14:08:05 INFO - PROCESS | 1639 | Values: 363.9 385.8 394.7 397.5 418.6 17196.6 18698.8 20227.1 21011.9 21026.0 20608.1 20995.4 21106.2 21375.8 21405.9 4553.7 5095.7 5786.1 5881.9 5933.1 5786.9 5840.9 5901.9 5911.3 5915.9 5861.7 6154.1 6493.6 6555.2 6591.8 6360.1 6526.7 6541.5 6558.1 6617.0 5729.4 5873.5 5880.4 5900.5 5907.8 12674.3 17690.9 18112.6 24771.9 24960.0 2564.4 2566.8 2570.6 2643.2 2840.2 9853.0 10179.5 10214.5 10276.4 12749.8 1569.0 1664.7 1697.9 1765.9 1824.3 110435.0 115126.0 117791.0 139754.3 140171.3 11582.9 11848.2 11944.1 11980.2 12075.9 15434.2 15640.1 15669.6 15678.6 15746.6 8081.2 8349.3 8350.4 8358.7 8387.3 6666.8 7972.4 7978.0 8105.6 8112.0 4769.6 4799.3 4859.4 4860.9 4954.2 1813.9 1998.5 2033.5 2074.7 2079.6 985.0 996.4 1006.1 1009.7 1049.7 20602.7 21125.2 21470.9 21615.4 21622.8 1008.3 1011.1 1039.6 1043.3 1045.1 10846.3 11539.8 11569.2 11579.8 11600.2 576939.0 608569.0 621437.0 659158.7 660687.0 127556.4 132786.0 135618.0 139107.6 148801.0 984.2 994.8 1019.0 1044.6 1050.3 14792.0 14849.7 19176.5 19246.3 19879.5 55211.0 58138.0 58322.5 58668.0 59097.0 68027.0 83537.0 84772.6 85720.0 86377.6 6193.6 8173.6 8316.9 8471.3 8473.7 8019.2 12419.2 12577.2 12680.0 12917.9 10816.3 11170.8 11216.1 11227.4 11396.5 8104.2 8789.6 10124.1 10850.1 11168.4 8964.9 9117.0 10490.4 11222.4 11249.6 8015.4 8066.9 8135.6 8423.3 8441.7 9834.8 10804.4 11092.7 11099.6 11109.2 14:08:05 INFO - PROCESS | 1639 | 14:08:05 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5391.76 Median:5098.76 stddev:3007.11 (59.0%) stddev-sans-first:2999.42 14:08:05 INFO - PROCESS | 1639 | Values: 1248.4 1408.9 1413.8 1430.3 1432.4 8031.8 8190.3 8428.7 8493.2 8513.3 8345.1 8482.2 8494.6 8506.0 8515.0 2228.6 2259.3 2280.9 2285.9 2286.7 1819.7 2246.2 2275.5 2285.5 2293.4 1755.9 2050.7 2207.5 2262.2 2266.2 2191.9 2244.2 2268.5 2274.4 2276.5 1940.2 2033.9 2232.4 2301.0 2311.3 8250.7 8379.4 8445.8 8478.7 8580.1 5178.3 5233.8 5239.9 5243.9 5254.2 2847.2 3416.7 3440.5 3499.0 3537.7 1192.6 1194.7 1226.5 1232.4 1252.8 10805.9 10892.8 10943.0 10973.6 11007.3 4182.5 4431.5 4501.2 4507.8 4517.6 8616.7 8745.1 8746.4 8748.9 8755.6 5095.3 5096.0 5097.7 5102.6 5116.5 4513.4 4553.5 4665.4 4674.5 4727.3 3585.1 3619.1 3651.9 3682.4 3717.8 1078.8 1134.1 1141.8 1142.3 1146.4 721.8 736.4 752.2 754.6 777.9 5244.6 5562.6 5611.8 5685.0 5699.4 481.7 488.7 540.1 545.5 578.5 3589.8 3638.1 3647.6 3702.9 3705.7 9709.9 10043.1 10111.0 10123.3 10162.4 9770.9 10070.9 10093.3 10096.6 10104.6 7774.3 7888.2 7891.6 7901.6 7930.5 4858.6 5602.2 5609.0 5640.5 5887.1 7566.9 8994.4 9273.8 9284.2 9303.4 8961.6 9154.0 9222.2 9327.5 9340.1 7590.6 7783.1 7790.7 7829.5 7847.1 7699.6 7876.2 7886.2 7889.2 7915.0 6050.0 7063.5 7122.4 7191.3 7215.4 6899.9 7000.6 7003.8 7036.8 7051.2 6212.9 6371.8 6408.6 6449.8 6476.9 4992.8 5036.1 5061.8 5093.9 5099.8 4969.0 5039.7 5051.8 5222.9 5269.5 14:08:05 INFO - PROCESS | 1639 | 14:08:05 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4484.41 Median:4977.91 stddev:1393.84 (28.0%) stddev-sans-first:1361.89 14:08:05 INFO - PROCESS | 1639 | Values: 298.7 352.5 368.2 370.7 373.0 4179.3 5038.9 5165.4 5287.6 5366.3 4310.9 4596.1 5296.5 5297.7 5347.6 4186.3 4186.3 4226.4 4226.7 4228.9 3702.7 4226.4 4226.5 4250.4 4261.7 3923.3 3941.9 3954.3 3962.6 3972.9 3931.9 3943.6 3952.0 3971.7 4001.4 3010.2 3417.8 3419.4 3420.5 3426.4 4708.6 4826.2 5142.7 5184.7 5202.2 4581.8 4603.7 4612.3 4613.0 4677.0 4830.9 5652.9 5678.6 5696.3 5737.2 3054.8 3545.7 3595.4 3609.8 3718.1 4562.8 5598.0 5843.3 5902.7 5961.9 3422.3 3773.4 3868.9 3899.8 3904.6 3909.3 3952.0 3953.1 3979.2 3979.9 3656.5 3941.6 4463.4 4594.1 4642.7 4456.4 5223.5 5340.8 5397.8 5493.7 1133.6 1181.6 1208.1 1256.8 1281.2 777.9 806.9 813.7 863.6 877.5 4355.6 4622.9 5022.2 5027.3 5038.7 4777.5 4803.6 5294.3 5430.2 5572.8 4270.4 4643.3 4802.7 5041.0 5049.4 2503.3 2644.0 2911.7 2941.6 2946.5 5363.9 5573.9 5777.2 5788.7 5808.4 5354.9 5428.0 5551.9 5737.6 6028.8 5082.6 5540.6 6036.8 6100.0 6104.5 4436.2 5652.6 5724.3 5871.5 5909.7 5510.0 5796.9 5828.7 5833.4 5841.9 5750.8 5877.7 5983.2 6101.7 6103.6 3821.9 3847.7 3893.0 3954.0 3966.2 4450.1 4554.5 5385.9 5890.6 5893.6 4666.2 5393.9 5414.4 5473.1 5665.6 5168.6 5194.6 5344.5 5492.0 5692.5 5150.5 5550.4 5556.4 5596.8 5637.9 5265.9 5410.9 5633.7 5734.6 5737.8 4917.2 4933.6 5613.6 5619.4 5657.3 14:08:05 INFO - PROCESS | 1639 | 14:08:05 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:18943.62 Median:3875.11 stddev:26730.85 (689.8%) stddev-sans-first:26770.72 14:08:05 INFO - PROCESS | 1639 | Values: 696.7 698.4 703.3 775.0 800.5 14210.1 14826.2 14891.5 15096.0 15311.0 13505.0 13620.7 13720.9 13737.0 13929.9 659.2 671.5 683.8 697.2 698.3 663.7 667.0 684.2 707.0 712.1 704.6 705.9 730.8 740.9 742.0 713.8 731.8 738.1 740.6 747.4 512.0 524.7 532.7 533.8 546.1 14590.9 14838.3 14883.7 14941.4 15070.7 13545.0 13576.3 13612.8 13749.8 13798.7 10891.2 11373.8 11460.8 11479.5 11644.4 3395.1 3478.6 3603.6 3607.7 3738.7 101682.5 102337.0 103018.5 103234.5 103498.4 10774.0 10775.2 10785.1 10887.8 11037.5 21010.2 21429.2 21452.2 21570.7 21761.7 6463.7 6926.5 7524.5 7616.5 7700.3 4011.5 4087.1 4139.1 4163.5 4191.7 3224.7 3588.2 3605.6 3611.5 3617.2 654.1 664.8 669.1 682.5 686.9 563.5 581.6 653.7 681.4 701.7 8477.8 9767.5 9970.8 10148.2 10190.0 582.8 661.8 681.2 687.4 696.0 4649.8 4805.8 4867.5 4889.2 4917.8 74350.6 92568.0 97338.0 97799.0 100318.0 57922.7 65542.3 72645.6 83605.2 86116.7 56078.0 62270.0 63650.7 72394.6 72415.0 9909.8 10596.3 10735.0 10782.1 10849.7 36129.8 46435.8 47164.0 48020.5 48489.0 65953.0 66776.0 67907.0 68072.0 68774.0 12314.8 12549.1 12656.7 12716.9 13081.0 11974.5 12005.2 12022.8 12045.3 12088.8 10991.6 12497.3 13081.6 13154.8 13422.9 13082.1 13200.2 13388.9 13446.4 13562.9 12654.2 12802.7 13247.8 13327.4 13581.3 10248.5 10629.9 10826.5 10980.9 11406.5 8324.0 9759.8 11308.2 11424.3 11586.6 14:08:05 INFO - PROCESS | 1639 | -------- Summary: end -------- 14:08:05 INFO - PROCESS | 1639 | 14:08:06 INFO - TEST-INFO | 1639: exit 0 14:08:06 INFO - TEST-OK | dromaeo_css | took 1115004ms 14:08:06 INFO - TEST-START | kraken 14:08:06 INFO - operating with platform_type : mac_ 14:08:06 INFO - Initialising browser for kraken test... 14:08:06 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmp_D_I4D/profile http://localhost:49244/getInfo.html) 14:08:09 INFO - PROCESS | 1651 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 14:08:09 INFO - PROCESS | 1651 | 2017-02-10 14:08:09.169 plugin-container[1652:9904] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 14:08:09 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:08:09 INFO - PROCESS | 1651 | 2017-02-10 14:08:09.176 plugin-container[1652:9904] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e1b, name = 'com.apple.CFPasteboardClient' 14:08:09 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:08:09 INFO - PROCESS | 1651 | 2017-02-10 14:08:09.176 plugin-container[1652:9904] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:08:09 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 14:08:09 INFO - PROCESS | 1651 | colorDepth:24 14:08:09 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 14:08:09 INFO - PROCESS | 1651 | __metrics 14:08:09 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 14:08:10 INFO - TEST-INFO | 1651: exit 0 14:08:10 INFO - Browser initialized. 14:08:10 INFO - Running cycle 1/1 for kraken test... 14:08:10 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmp_D_I4D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:08:12 INFO - PROCESS | 1653 | 2017-02-10 14:08:12.431 plugin-container[1654:10020] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463b, name = 'com.apple.tsm.portname' 14:08:12 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:08:12 INFO - PROCESS | 1653 | 2017-02-10 14:08:12.432 plugin-container[1654:10020] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 14:08:12 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:08:12 INFO - PROCESS | 1653 | 2017-02-10 14:08:12.432 plugin-container[1654:10020] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:08:22 INFO - PROCESS | 1653 | RSS: Main: 233054208 14:08:22 INFO - PROCESS | 1653 | 14:08:25 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 14:08:26 INFO - PROCESS | 1653 | RSS: Main: 247443456 14:08:26 INFO - PROCESS | 1653 | 14:08:29 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 14:08:29 INFO - PROCESS | 1653 | RSS: Main: 256299008 14:08:29 INFO - PROCESS | 1653 | 14:08:33 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 14:08:33 INFO - PROCESS | 1653 | RSS: Main: 258207744 14:08:33 INFO - PROCESS | 1653 | 14:08:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 14:08:36 INFO - PROCESS | 1653 | RSS: Main: 259047424 14:08:36 INFO - PROCESS | 1653 | 14:08:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:08:39 INFO - PROCESS | 1653 | RSS: Main: 260812800 14:08:39 INFO - PROCESS | 1653 | 14:08:42 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 14:08:43 INFO - PROCESS | 1653 | RSS: Main: 266862592 14:08:43 INFO - PROCESS | 1653 | 14:08:46 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 14:08:46 INFO - PROCESS | 1653 | RSS: Main: 265961472 14:08:46 INFO - PROCESS | 1653 | 14:08:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 14:08:50 INFO - PROCESS | 1653 | RSS: Main: 265265152 14:08:50 INFO - PROCESS | 1653 | 14:08:52 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:08:53 INFO - PROCESS | 1653 | RSS: Main: 265392128 14:08:53 INFO - PROCESS | 1653 | 14:08:55 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:08:55 INFO - PROCESS | 1653 | RSS: Main: 265404416 14:08:55 INFO - PROCESS | 1653 | 14:08:58 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:08:58 INFO - PROCESS | 1653 | RSS: Main: 265523200 14:08:58 INFO - PROCESS | 1653 | 14:09:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:09:02 INFO - PROCESS | 1653 | RSS: Main: 265744384 14:09:02 INFO - PROCESS | 1653 | 14:09:05 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:09:06 INFO - PROCESS | 1653 | RSS: Main: 262975488 14:09:06 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 14:09:08 INFO - PROCESS | 1653 | RSS: Main: 266452992 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | __start_tp_report 14:09:08 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 14:09:08 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 14:09:08 INFO - PROCESS | 1653 | |i|pagename|runs| 14:09:08 INFO - PROCESS | 1653 | |0;ai-astar;105;123;178;124;118;145;111;169;174;117 14:09:08 INFO - PROCESS | 1653 | |1;audio-beat-detection;155;149;144;152;116;115;122;146;137;116 14:09:08 INFO - PROCESS | 1653 | |2;audio-dft;198;213;252;267;201;197;198;196;227;259 14:09:08 INFO - PROCESS | 1653 | |3;audio-fft;84;60;61;85;62;63;79;85;96;82 14:09:08 INFO - PROCESS | 1653 | |4;audio-oscillator;90;77;84;73;72;72;89;107;102;113 14:09:08 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;100;137;116;91;111;85;139;138;107;89 14:09:08 INFO - PROCESS | 1653 | |6;imaging-darkroom;92;97;146;159;98;101;116;159;98;97 14:09:08 INFO - PROCESS | 1653 | |7;imaging-desaturate;151;125;127;131;125;147;143;91;116;90 14:09:08 INFO - PROCESS | 1653 | |8;json-parse-financial;64;62;63;66;65;66;63;66;65;65 14:09:08 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;69;72;91;113;110;110;70;70;71;71 14:09:08 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;89;90;89;103;110;109;112;91;91;86 14:09:08 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;147;148;142;153;157;151;151;155;181;166 14:09:08 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;189;174;205;178;175;160;235;174;237;241 14:09:08 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;70;67;69;71;68;69;66;69;72;87 14:09:08 INFO - PROCESS | 1653 | __end_tp_report 14:09:08 INFO - PROCESS | 1653 | __start_cc_report 14:09:08 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,984 14:09:08 INFO - PROCESS | 1653 | __end_cc_report 14:09:08 INFO - PROCESS | 1653 | __startTimestamp1486764548494__endTimestamp 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | ------- Summary: start ------- 14:09:08 INFO - PROCESS | 1653 | Number of tests: 14 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:136.40 Median:134.50 stddev:27.81 (20.7%) stddev-sans-first:27.08 14:09:08 INFO - PROCESS | 1653 | Values: 105.0 123.0 178.0 124.0 118.0 145.0 111.0 169.0 174.0 117.0 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:145.00 stddev:16.27 (11.2%) stddev-sans-first:15.60 14:09:08 INFO - PROCESS | 1653 | Values: 155.0 149.0 144.0 152.0 116.0 115.0 122.0 146.0 137.0 116.0 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:220.80 Median:220.00 stddev:28.44 (12.9%) stddev-sans-first:28.94 14:09:08 INFO - PROCESS | 1653 | Values: 198.0 213.0 252.0 267.0 201.0 197.0 198.0 196.0 227.0 259.0 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:75.70 Median:83.00 stddev:12.98 (15.6%) stddev-sans-first:13.41 14:09:08 INFO - PROCESS | 1653 | Values: 84.0 60.0 61.0 85.0 62.0 63.0 79.0 85.0 96.0 82.0 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:75.00 stddev:15.13 (20.2%) stddev-sans-first:16.03 14:09:08 INFO - PROCESS | 1653 | Values: 90.0 77.0 84.0 73.0 72.0 72.0 89.0 107.0 102.0 113.0 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:111.30 Median:138.50 stddev:20.84 (15.0%) stddev-sans-first:21.70 14:09:08 INFO - PROCESS | 1653 | Values: 100.0 137.0 116.0 91.0 111.0 85.0 139.0 138.0 107.0 89.0 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:116.30 Median:94.50 stddev:27.42 (29.0%) stddev-sans-first:27.64 14:09:08 INFO - PROCESS | 1653 | Values: 92.0 97.0 146.0 159.0 98.0 101.0 116.0 159.0 98.0 97.0 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:124.60 Median:145.00 stddev:21.04 (14.5%) stddev-sans-first:20.03 14:09:08 INFO - PROCESS | 1653 | Values: 151.0 125.0 127.0 131.0 125.0 147.0 143.0 91.0 116.0 90.0 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:65.00 stddev:1.43 (2.2%) stddev-sans-first:1.51 14:09:08 INFO - PROCESS | 1653 | Values: 64.0 62.0 63.0 66.0 65.0 66.0 63.0 66.0 65.0 65.0 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:84.70 Median:70.50 stddev:19.25 (27.3%) stddev-sans-first:19.56 14:09:08 INFO - PROCESS | 1653 | Values: 69.0 72.0 91.0 113.0 110.0 110.0 70.0 70.0 71.0 71.0 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:97.00 Median:89.00 stddev:10.24 (11.5%) stddev-sans-first:10.45 14:09:08 INFO - PROCESS | 1653 | Values: 89.0 90.0 89.0 103.0 110.0 109.0 112.0 91.0 91.0 86.0 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:155.10 Median:154.00 stddev:11.15 (7.2%) stddev-sans-first:11.43 14:09:08 INFO - PROCESS | 1653 | Values: 147.0 148.0 142.0 153.0 157.0 151.0 151.0 155.0 181.0 166.0 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.80 Median:197.00 stddev:30.51 (15.5%) stddev-sans-first:32.23 14:09:08 INFO - PROCESS | 1653 | Values: 189.0 174.0 205.0 178.0 175.0 160.0 235.0 174.0 237.0 241.0 14:09:08 INFO - PROCESS | 1653 | 14:09:08 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:69.50 stddev:5.96 (8.6%) stddev-sans-first:6.31 14:09:08 INFO - PROCESS | 1653 | Values: 70.0 67.0 69.0 71.0 68.0 69.0 66.0 69.0 72.0 87.0 14:09:08 INFO - PROCESS | 1653 | -------- Summary: end -------- 14:09:08 INFO - PROCESS | 1653 | 14:09:09 INFO - TEST-INFO | 1653: exit 0 14:09:09 INFO - TEST-OK | kraken | took 62955ms 14:09:09 INFO - SUITE-END | took 1177s 14:09:09 INFO - Completed test suite (00:19:38) 14:09:09 INFO - javascript benchmark 14:09:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7046.55180890087, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3310.2386451116226, 3322.5514555534996, 3334.116459138328, 3339.0817525180573, 3358.9251439539344, 4930.926036109461, 5415.593830462722, 5514.558835293177, 5553.833384998451, 5573.164025396192, 3890.941635875457, 5370.140777475604, 5442.863928401792, 5446.7276636168135, 5474.863128421792, 4284.657227421799, 4566.223741963858, 5021.47274536173, 5048.444671086178, 5097.158968770167, 5049.116404629175, 5148.2535032420465, 5151.74241287936, 5194.870128246786, 5214.478552144774, 5167.431582525919, 5181.404138524062, 5187.27378167055, 5188.9362680650565, 5233.110371236899, 601.8981206651684, 5073.797048118071, 5078.5175408336145, 5118.206677964921, 5166.612504062192, 4604.894825241951, 4694.741789201593, 5122.359705036871, 5156.587473002151, 5158.1746920492915, 4828.61371090312, 5364.973175134139, 5431.212474191259, 5439.9936011837935, 5457.208704737831, 5851.853703657443, 5924.348321684612, 6095.2685677718955, 6123.326434092246, 6188.84527886802, 6107.7957376609465, 6142.161149281699, 6171.13604095427, 6227.19046523949, 6229.3770622937345, 5745.68398738065, 5749.540036797046, 5757.222774925415, 5766.509846662997, 5769.740361683735, 4796.472388037313, 5024.422191447957, 6086.969565152234, 6283.7800676976085, 6289.811305660837, 5814.156947242626, 5958.612690175125, 5976.760929562769, 6024.0, 6104.359042300494, 5085.949140508547, 6100.908486372708, 6129.1419201311855, 6135.631862088289, 6172.814815555541, 5783.16144159103, 5906.970465147732, 5992.67040312787, 6012.849678757979, 6014.789482368096, 6087.847803804941, 6099.420555047263, 6109.572329936951, 6134.601250918676, 6138.508919286446, 4867.683600565952, 4870.196417591129, 4871.0, 4877.073356062337, 4906.239532872411, 4294.184105020098, 4297.290946993773, 4347.782610869507, 4366.366876802846, 4385.0, 5637.154426835926, 5670.376258611549, 5698.914516282259, 5700.486956173964, 5713.628614140068, 6072.36240194782, 6106.572539922252, 6135.570510064342, 6161.84595385119, 6183.310260036898, 4865.759231395971, 5446.618736688473, 5574.972125139429, 5663.631863928831, 5668.064769313017, 5269.4993975573025, 5314.256004159421, 5316.0962636351915, 5319.893602127936, 5385.919211211835, 6231.9688401557705, 6266.27937787142, 6276.905846412307, 6280.057991301342, 6292.811215663537, 6071.574989750675, 6163.921313769983, 6176.505879529537, 6198.6280823150755, 6199.28708198563, 6113.0, 6117.766507567632, 6168.475679567107, 6184.5980011299125, 6267.091271765477, 6111.152629350003, 6158.0147176451555, 6163.537734670007, 6167.167432396569, 6168.1056246844855, 5996.760129594767, 6203.689815509297, 6209.59637623553, 6221.595596286408, 6245.812625621239, 4841.176999909953, 5755.280589926259, 5895.380984996514, 5911.379305173067, 5991.0, 5216.061109000417, 5676.886462270649, 5961.701914904324, 5998.280206375263, 6005.0, 4846.733429661403, 5712.057510510719, 5792.9710351447975, 5814.418558144152, 5839.299284085937, 5768.50967667753, 5867.647941123546, 5889.823305300676, 5921.733521991435, 5984.700764961647, 5758.712064396847, 5769.79805706815, 5771.422857714195, 5787.710614469175, 5792.797252096157], "value": 5485.99147598431, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.93700881876543, 482.8117034356601, 505.0177404947379, 510.74357081579313, 519.3352508788751, 27207.5918861217, 30262.69737302626, 38740.80629596852, 40396.19207615846, 40415.0, 40860.59139408605, 41246.175076498526, 41339.37990930131, 41347.0, 41405.37891931624, 9120.8631870522, 9275.860862087075, 9295.163435290822, 9298.16316531512, 9326.0, 7814.413918956073, 8010.67957281708, 9239.39943903644, 10805.0, 11096.667099987013, 10952.616658416957, 10987.945060274727, 11002.889971100307, 11054.392008439532, 11057.778844423106, 8400.781886626435, 9473.478958657272, 9771.12059914611, 10398.0, 11046.392448415334, 10813.134949204044, 10899.346039237591, 10901.672949811518, 10909.18181136418, 10943.0, 38633.03417414559, 38698.13018698136, 39009.829705108896, 39045.41431878524, 39194.60805391938, 3818.1409182933894, 3848.5189351331082, 3862.497875276224, 3870.825812838429, 3957.6240257175523, 28081.578776318373, 29207.707922920923, 29237.122886313446, 29321.48142222947, 29372.97194598179, 3044.9543256851166, 3112.128603990886, 3207.5188721691698, 3243.5296881951986, 3247.3342964692183, 340162.2991885049, 342373.0, 342583.0, 342793.0, 342947.0, 58138.4186158133, 58611.0, 58626.0, 58639.70680146572, 58680.41319586835, 54904.450955489934, 55276.17085743717, 61540.69229653823, 62847.0, 62854.0, 12664.556740514037, 14727.55817325482, 14903.925480372745, 14923.253837308308, 14950.626234344229, 11490.655280341603, 11583.6524904253, 11598.18812683121, 11603.6518904433, 11606.24559403636, 16141.677166456606, 16142.838571614368, 16234.675306493804, 16238.538531532218, 16245.593860153354, 6587.80236592903, 6598.538102332791, 6634.0712300278, 6663.167104111986, 6668.199816022108, 802.7872613757359, 816.8897198878194, 817.1134319263547, 843.3211264931666, 848.5460278750838, 28254.434911301658, 29300.0, 29427.558586621217, 29698.54521821744, 30123.548146777815, 983.4296108257194, 1007.3804610164842, 1022.3508072373947, 1050.5535147562255, 1169.8771628979005, 15272.16003119817, 15275.465358712392, 15325.23373831304, 15358.923205384124, 15414.84585154134, 561348.0, 562436.0, 565136.0, 566719.0, 568885.4891608661, 57115.428845711845, 57987.71006144942, 58097.41902581005, 58258.92187546831, 58316.12525812116, 1312.4618906248393, 1354.4243696429176, 1354.5056054540023, 1360.5510181640036, 1366.631009627395, 27789.305267368476, 28720.712792871804, 28849.711502884704, 28867.8556607224, 29326.853365733034, 48661.02677946351, 49025.754871225414, 49030.0, 49043.77390565264, 49165.508344916096, 504083.39791505504, 508239.0, 508293.0, 508958.0, 509466.0, 24335.391615209053, 24923.875380623707, 25006.374840628403, 25182.0, 38319.467221310835, 40784.98037549085, 41110.794446027576, 41321.380179296735, 41740.1651966965, 41906.74279771612, 33145.0, 33887.322253555285, 34368.48447273343, 35337.293254135286, 35543.64456355404, 33849.49225761566, 34223.4866477008, 34887.953361399195, 34969.475457867644, 35461.82269088638, 33866.0, 34327.65672343245, 34479.13802154966, 34593.827030865694, 34691.4796278051, 29620.0, 30734.846325768227, 30831.845840770653, 31104.377912442076, 31207.687923120477, 22301.330960071224, 22401.983821456146, 29446.705532945252, 31967.520487192247, 33006.00981970545], "value": 18998.884137054563, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.9290338384016, 385.8309322752059, 394.73947194851394, 397.4832717467292, 418.61069205638745, 17196.570085747862, 18698.813011869875, 20227.089780959854, 21011.894940525275, 21026.0, 20608.072636731347, 20995.370138895818, 21106.15575376983, 21375.786242137576, 21405.892970535126, 4553.68124231304, 5095.71973541456, 5786.08539627142, 5881.8823623527505, 5933.13969474426, 5786.855328616776, 5840.883182336351, 5901.881962360751, 5911.349751527328, 5915.940840591604, 5861.677607731573, 6154.138420621117, 6493.577917435376, 6555.180602424697, 6591.769288074918, 6360.14138091361, 6526.706298216568, 6541.47668186544, 6558.114654521626, 6617.007448882682, 5729.398413166641, 5873.500752436048, 5880.441358070976, 5900.498457631106, 5907.793227237026, 12674.302913339854, 17690.91154544219, 18112.637747244982, 24771.876140619363, 24960.0, 2564.4101856573093, 2566.8203225774205, 2570.5630042892753, 2643.246674697721, 2840.1930653862046, 9853.0, 10179.541920613516, 10214.489275536194, 10276.434796086212, 12749.788770066829, 1569.0115227406661, 1664.7253203221576, 1697.889637173592, 1765.9028753418556, 1824.3158815444208, 110435.0, 115126.0, 117791.0, 139754.30122849322, 140171.29914350464, 11582.94208528952, 11848.229865058744, 11944.104192185621, 11980.221285616408, 12075.879241207476, 15434.15112168819, 15640.139792311533, 15669.608259793597, 15678.608034799221, 15746.606334841721, 8081.232282933112, 8349.290310323691, 8350.415470916978, 8358.665653373797, 8387.329013679013, 6666.83332916681, 7972.441929064909, 7977.960110199412, 8105.594720264081, 8112.02655681322, 4769.594584460359, 4799.328094066834, 4859.441164266084, 4860.854174374775, 4954.232094025432, 1813.8820976636475, 1998.5103649605649, 2033.501792060925, 2074.6991686205415, 2079.55289612734, 985.0, 996.3944285692861, 1006.1296978113879, 1009.7021378693156, 1049.7113293844254, 20602.690959635616, 21125.154993800385, 21470.892645536984, 21615.35153945384, 21622.78377216239, 1008.2740426892625, 1011.110223003752, 1039.5633833789827, 1043.2749239278628, 1045.1012129568564, 10846.349219046882, 11539.76920461586, 11569.247998880046, 11579.76840463186, 11600.187986841009, 576939.0, 608569.0, 621437.0, 659158.6757191779, 660687.0, 127556.36221818831, 132786.0, 135618.0, 139107.60892390946, 148801.0, 984.17329443268, 994.8360418310594, 1019.0166489337824, 1044.5612842605972, 1050.2963014779964, 14792.0, 14849.703005940037, 19176.52058698488, 19246.326378576683, 19879.503012424804, 55211.0, 58138.0, 58322.54193645023, 58668.0, 59097.0, 68026.97959530512, 83537.0, 84772.57613711891, 85720.0, 86377.56811215903, 6193.628382296896, 8173.632186551738, 8316.875246871423, 8471.32229421657, 8473.703420380258, 8019.238172373615, 12419.19275247106, 12577.245365278113, 12680.048996325497, 12917.870821291666, 10816.296940698829, 11170.832437513607, 11216.102711783202, 11227.438628068727, 11396.544138234378, 8104.230098140667, 8789.648414063366, 10124.13944814699, 10850.131989440984, 11168.385738784445, 8964.865527017231, 9117.042710515301, 10490.423026733602, 11222.438878056228, 11249.606263780728, 8015.398845086525, 8066.919330806617, 8135.552544610104, 8423.284020858297, 8441.70454034106, 9834.754131146492, 10804.40575768309, 11092.725991171628, 11099.611513596985, 11109.166812489257], "value": 9662.558796129542, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1248.3882897380283, 1408.9084209526375, 1413.8303403591572, 1430.2634143416142, 1432.412710788577, 8031.83936321274, 8190.2628763411285, 8428.747137585882, 8493.235608795216, 8513.276371508422, 8345.123762004992, 8482.236598706115, 8494.575271236445, 8506.0, 8514.95742521288, 2228.621134407155, 2259.3109101723967, 2280.851744636602, 2285.874276914769, 2286.7255929288426, 1819.6633622779827, 2246.2025980776834, 2275.4538910661486, 2285.4514916420108, 2293.4381076636164, 1755.9464321407117, 2050.682144267641, 2207.5033117548583, 2262.1969200933677, 2266.195500597281, 2191.931222766277, 2244.2033078257227, 2268.5236100418933, 2274.3972847195505, 2276.4536511237347, 1940.2045161483866, 2033.8779673219653, 2232.4195709115747, 2300.976990230093, 2311.2719493359537, 8250.669973201066, 8379.413441059129, 8445.831083378298, 8478.66085356585, 8580.056193818675, 5178.326817513736, 5233.790648374061, 5239.921401178985, 5243.9213411798855, 5254.185601231815, 2847.2454799478155, 3416.692497675196, 3440.501127336547, 3498.9650103498893, 3537.7169826413824, 1192.6004788395899, 1194.7132688154868, 1226.5400474821943, 1232.3961258983124, 1252.843394575678, 10805.945970270177, 10892.836607450894, 10943.0, 10973.561057557688, 11007.284526505751, 4182.456280683492, 4431.534688857688, 4501.167284052461, 4507.797149128297, 4517.6160026397465, 8616.698415555426, 8745.081766414434, 8746.387752857367, 8748.8687669685, 8755.649774009096, 5095.31213286209, 5095.974520127375, 5097.6941383517105, 5102.617303702237, 5116.462771409023, 4513.368128462018, 4553.4991150973365, 4665.430945326828, 4674.462436819741, 4727.338172655831, 3585.1216451969435, 3619.077135330474, 3651.940937128203, 3682.3924052531033, 3717.7955212463576, 1078.795028944498, 1134.0927258193412, 1141.8230174322994, 1142.331735934471, 1146.3866831245243, 721.7582109993211, 736.3630459652362, 752.2139364364143, 754.5738554132694, 777.9144294127641, 5244.632875698664, 5562.610617256834, 5611.80358687444, 5684.971575142098, 5699.373068962494, 481.7398604753394, 488.7116601205306, 540.106124364178, 545.5035917315224, 578.5198285423173, 3589.766665166756, 3638.1450359166106, 3647.62834432341, 3702.9259414811554, 3705.6850167736043, 9709.854352184722, 10043.146332561813, 10111.0, 10123.341982771244, 10162.441065741308, 9770.853437198448, 10070.89220185779, 10093.343932644355, 10096.646617368504, 10104.595816167419, 7774.339181169664, 7888.211178882066, 7891.565963871927, 7901.60491975399, 7930.484518506279, 4858.562729354234, 5602.187682785891, 5609.0, 5640.520555752807, 5887.0580707086665, 7566.924330756842, 8994.370394072352, 9273.814523709352, 9284.210842078499, 9303.441793492142, 8961.641534338554, 9154.038825923182, 9222.170004699608, 9327.486988215713, 9340.065993400878, 7590.582517961565, 7783.104942931635, 7790.688372465038, 7829.530228186327, 7847.0975837777105, 7699.615019248903, 7876.173001834725, 7886.171951944963, 7889.2110788920645, 7914.960425197837, 6050.0, 7063.5055546113335, 7122.358987691133, 7191.280871912767, 7215.422766178588, 6899.931000689929, 7000.61496617691, 7003.789886303419, 7036.824079398056, 7051.153861536648, 6212.937870621236, 6371.808845734636, 6408.551401401857, 6449.774257900951, 6476.935230647821, 4992.825251116194, 5036.06832735945, 5061.848144555669, 5093.8726531837, 5099.821506247264, 4968.975155124202, 5039.748012599429, 5051.823186188466, 5222.9216561750845, 5269.525742683177], "value": 4274.847444058335, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.6998066942723, 352.513531326769, 368.15141099765066, 370.69602925601004, 372.9608391118934, 4179.289520781467, 5038.924416133761, 5165.354330708667, 5287.629865909388, 5366.275552800371, 4310.922269432642, 4596.0578081493295, 5296.470352964701, 5297.655922143834, 5347.59893008024, 4186.267403204427, 4186.2883309837325, 4226.429432026668, 4226.682998775089, 4228.873133805975, 3702.685271751891, 4226.408302837605, 4226.45056142701, 4250.426192464024, 4261.680373971949, 3923.274194274054, 3941.9211615767667, 3954.2882281189372, 3962.5839286874893, 3972.940405893914, 3931.9017024574327, 3943.6253555912285, 3951.9844879111574, 3971.7290467050557, 4001.379786133154, 3010.1722026442685, 3417.79493230407, 3419.401604719189, 3420.452727563603, 3426.400379933502, 4708.57622813945, 4826.22780355145, 5142.665726727788, 5184.6629969052265, 5202.193659982709, 4581.770911454414, 4603.746793926332, 4612.262038073925, 4613.031263434683, 4677.0, 4830.855074347775, 5652.943470565324, 5678.630888992202, 5696.287964004499, 5737.2254745609225, 3054.7861649684532, 3545.663161999606, 3595.352836489431, 3609.8375573099274, 3718.0704823794053, 4562.817487300504, 5597.984725498817, 5843.298804143487, 5902.734376953004, 5961.850953726148, 3422.281320922634, 3773.3773927302236, 3868.9226215475533, 3899.810557779848, 3904.5509766376663, 3909.315869722844, 3951.980240098781, 3953.090789118519, 3979.164375481181, 3979.8607048753156, 3656.469811877263, 3941.5664276929515, 4463.4420697412825, 4594.104149690778, 4642.651801114863, 4456.3538286948215, 5223.503767142115, 5340.839774806762, 5397.8380648580605, 5493.725313734298, 1133.5749094089717, 1181.6041626055369, 1208.1361826294242, 1256.8051951947464, 1281.2248589603341, 777.9338756205673, 806.9031716194095, 813.7111325479488, 863.6027427383349, 877.4998250996871, 4355.586219309161, 4622.913615300402, 5022.221555658879, 5027.271045698353, 5038.7073881383885, 4777.4505931818285, 4803.55018940343, 5294.338207724035, 5430.185472179126, 5572.804951826666, 4270.380794784739, 4643.257078867365, 4802.663813533089, 5040.991801639613, 5049.394072711225, 2503.2865633294787, 2644.0, 2911.7379435850876, 2941.573471846613, 2946.499095153829, 5363.927214557026, 5573.854066184548, 5777.19119323295, 5788.710564471759, 5808.361080281165, 5354.919676204782, 5427.972860135674, 5551.944480555224, 5737.598368114275, 6028.849278767978, 5082.567981721595, 5540.63985840919, 6036.758529658764, 6099.969500152471, 6104.542159338067, 4436.223660859401, 5652.6043176978865, 5724.284464441945, 5871.500922421642, 5909.661041511532, 5510.0, 5796.913046304225, 5828.708564571839, 5833.366657335952, 5841.912371314519, 5750.7987220447085, 5877.706114694333, 5983.222181116427, 6101.725422355916, 6103.6032657877095, 3821.86606982283, 3847.6729477994686, 3892.9829382227867, 3953.9802300988313, 3966.186931678941, 4450.065486247924, 4554.453465584019, 5385.865353366197, 5890.558208134321, 5893.557983151367, 4666.200279832074, 5393.919091213556, 5414.377346605191, 5473.124300111963, 5665.603407761417, 5168.58651307902, 5194.584433245256, 5344.545713614386, 5491.972540137273, 5692.5445964321925, 5150.510701483353, 5550.391316081435, 5556.444355564411, 5596.776128954796, 5637.943620563742, 5265.947340526545, 5410.918836217538, 5633.7324351874995, 5734.569907256889, 5737.770489180386, 4917.164082105979, 4933.605311575137, 5613.578981576316, 5619.438056194348, 5657.264555607745], "value": 4048.142250198928, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.7491702986924, 698.3993765361785, 703.3248081841433, 774.9770303199775, 800.5076877720202, 14210.147391156524, 14826.184559849204, 14891.478798242064, 15096.0187587807, 15311.004784688987, 13505.0, 13620.72758544831, 13720.862791372083, 13737.0, 13929.860701392983, 659.1694464974144, 671.5064427645701, 683.7982795075452, 697.2225968045643, 698.2703075286338, 663.7477758451806, 666.9662023863017, 684.1550684904139, 706.9505134640551, 712.0991945189339, 704.5702121705756, 705.9364657180853, 730.817295676081, 740.9222031686676, 742.0353540397489, 713.8179764160158, 731.8463122744257, 738.1290077708302, 740.6185814305637, 747.4319517166938, 511.96071973893055, 524.6615932723372, 532.6564365983958, 533.8478533617939, 546.0662267522541, 14590.854091459056, 14838.258087095603, 14883.70232595342, 14941.402343906231, 15070.698586028218, 13545.0, 13576.309340201313, 13612.795808062887, 13749.793753093712, 13798.65503362424, 10891.237613367068, 11373.772524549546, 11460.828087578693, 11479.540818367255, 11644.41777911101, 3395.134240768613, 3478.5999610044914, 3603.6396360364015, 3607.6753092221825, 3738.7195960302815, 101682.47476287861, 102337.0, 103018.48490757498, 103234.48382758113, 103498.41253968635, 10774.0, 10775.191860610328, 10785.13718902486, 10887.83668244977, 11037.503312350964, 21010.15959361639, 21429.249976251067, 21452.249171279243, 21570.676439853414, 21761.67357489607, 6463.741450341934, 6926.515143939977, 7524.548527088392, 7616.466847320635, 7700.268474494914, 4011.498562679665, 4087.1338339881986, 4139.068709540389, 4163.521195062546, 4191.664666826647, 3224.6775322467565, 3588.1567831559564, 3605.639436056426, 3611.5485564304463, 3617.2403795203304, 654.1202083198091, 664.764008776887, 669.0866966590667, 682.5085938124543, 686.9450443964471, 563.4872266237704, 581.5841673203595, 653.650297091054, 681.4139839737821, 701.6916134931524, 8477.830443391098, 9767.51162441875, 9970.800583988279, 10148.179614683115, 10190.031946965026, 582.8105865593699, 661.7551505942832, 681.151965802579, 687.412262515552, 695.994288253479, 4649.767511624405, 4805.7597120143855, 4867.466747974377, 4889.193283108319, 4917.803287868517, 74350.6282468595, 92568.0, 97338.0, 97799.0, 100318.0, 57922.7103864478, 65542.34457655363, 72645.63677181686, 83605.16394836096, 86116.70824937632, 56078.0, 62270.0, 63650.681746591894, 72394.63802680952, 72415.0, 9909.801803963735, 10596.258261921592, 10734.980176883184, 10782.08352290064, 10849.67450976472, 36129.81935090413, 46435.767821160676, 47164.0, 48020.51979480161, 48489.0, 65953.0, 66776.0, 67907.0, 68071.97892031525, 68774.0, 12314.760371924385, 12549.121561490609, 12656.6835829105, 12716.872831271568, 13081.0, 11974.521019159136, 12005.219660722025, 12022.819657704966, 12045.33750643723, 12088.818667720167, 10991.61529346469, 12497.25016499013, 13081.607551773463, 13154.802677959648, 13422.932885335511, 13082.084254102121, 13200.207987520394, 13388.866111338762, 13446.394912228778, 13562.93218533901, 12654.240745555297, 12802.74394512086, 13247.80128298057, 13327.400711914632, 13581.32093395346, 10248.487575621339, 10629.89370106289, 10826.458677066274, 10980.945095274472, 11406.54373825071, 8323.959505061866, 9759.75600609962, 11308.151888608223, 11424.314541127558, 11586.594469193538], "value": 7023.608027426579, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1677.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 123.0, 178.0, 124.0, 118.0, 145.0, 111.0, 169.0, 174.0, 117.0], "value": 136.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 149.0, 144.0, 152.0, 116.0, 115.0, 122.0, 146.0, 137.0, 116.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 213.0, 252.0, 267.0, 201.0, 197.0, 198.0, 196.0, 227.0, 259.0], "value": 220.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 61.0, 85.0, 62.0, 63.0, 79.0, 85.0, 96.0, 82.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 77.0, 84.0, 73.0, 72.0, 72.0, 89.0, 107.0, 102.0, 113.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 137.0, 116.0, 91.0, 111.0, 85.0, 139.0, 138.0, 107.0, 89.0], "value": 111.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 97.0, 146.0, 159.0, 98.0, 101.0, 116.0, 159.0, 98.0, 97.0], "value": 116.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 125.0, 127.0, 131.0, 125.0, 147.0, 143.0, 91.0, 116.0, 90.0], "value": 124.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 66.0, 65.0, 66.0, 63.0, 66.0, 65.0, 65.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 91.0, 113.0, 110.0, 110.0, 70.0, 70.0, 71.0, 71.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 89.0, 103.0, 110.0, 109.0, 112.0, 91.0, 91.0, 86.0], "value": 97.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 142.0, 153.0, 157.0, 151.0, 151.0, 155.0, 181.0, 166.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 174.0, 205.0, 178.0, 175.0, 160.0, 235.0, 174.0, 237.0, 241.0], "value": 196.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 69.0, 71.0, 68.0, 69.0, 66.0, 69.0, 72.0, 87.0], "value": 70.8, "unit": "score"}]}]} 14:09:09 INFO - Return code: 0 14:09:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:09:09 ERROR - # TBPL SUCCESS # 14:09:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:09:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:09:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:09:09 INFO - 2017-02-10 14:09:09.780 screenresolution[1658:10405] starting screenresolution argv=screenresolution get 14:09:09 INFO - 2017-02-10 14:09:09.784 screenresolution[1658:10405] Display 0: 1600x1200x32@60 14:09:09 INFO - 2017-02-10 14:09:09.790 screenresolution[1659:10408] starting screenresolution argv=screenresolution list 14:09:09 INFO - Available Modes on Display 0 14:09:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:09:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:09:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:09:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:09:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:09:10 INFO - Intel Iris: 14:09:10 INFO - Chipset Model: Intel Iris 14:09:10 INFO - Type: GPU 14:09:10 INFO - Bus: Built-In 14:09:10 INFO - VRAM (Dynamic, Max): 1536 MB 14:09:10 INFO - Vendor: Intel (0x8086) 14:09:10 INFO - Device ID: 0x0a2e 14:09:10 INFO - Revision ID: 0x0009 14:09:10 INFO - Displays: 14:09:10 INFO - Display: 14:09:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:09:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:09:10 INFO - Display Serial Number: 1600x1200 60 14:09:10 INFO - Main Display: Yes 14:09:10 INFO - Mirror: Off 14:09:10 INFO - Online: Yes 14:09:10 INFO - Rotation: Supported 14:09:10 INFO - Return code: 0 14:09:10 INFO - Running post-action listener: _resource_record_post_action 14:09:10 INFO - [mozharness: 2017-02-10 22:09:10.029387Z] Finished run-tests step (success) 14:09:10 INFO - Running post-run listener: _resource_record_post_run 14:09:10 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 39823872; Write bytes: 560577024; Read time: 385; Write time: 1995 14:09:10 INFO - TinderboxPrint: CPU usage
54.4% 14:09:10 INFO - TinderboxPrint: I/O read bytes / time
39,823,872 / 385 14:09:10 INFO - TinderboxPrint: I/O write bytes / time
560,577,024 / 1,995 14:09:10 INFO - TinderboxPrint: CPU idle
2,190.3 (45.6%) 14:09:10 INFO - TinderboxPrint: CPU system
969.1 (20.2%) 14:09:10 INFO - TinderboxPrint: CPU user
1,646.3 (34.3%) 14:09:10 INFO - TinderboxPrint: Swap in / out
292,413,440 / 0 14:09:10 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 249199104; Write bytes: 245245952; Read time: 17910; Write time: 529 14:09:10 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 37739008; Write bytes: 301949440; Read time: 364; Write time: 1422 14:09:10 INFO - Running post-run listener: _upload_blobber_files 14:09:10 INFO - Blob upload gear active. 14:09:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:09:10 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:09:10 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'] 14:09:10 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 14:09:10 INFO - (blobuploader) - INFO - Open directory for files ... 14:09:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:09:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:09:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:09:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:09:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:09:11 INFO - (blobuploader) - INFO - Done attempting. 14:09:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:09:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:09:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:09:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:09:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:09:12 INFO - (blobuploader) - INFO - Done attempting. 14:09:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:09:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:09:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:09:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:09:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:09:13 INFO - (blobuploader) - INFO - Done attempting. 14:09:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:09:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:09:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:09:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:09:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:09:15 INFO - (blobuploader) - INFO - Done attempting. 14:09:15 INFO - (blobuploader) - INFO - Iteration through files over. 14:09:15 INFO - Return code: 0 14:09:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:09:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:09:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5e06168a02e60ad9d85be54951b34fdd7bb08828dbe92cf3348872fbc57fdabcdfea93bb651941c964cf1d437364d9f3ade394adefb4374f01c93974c8b23c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c59f0fabc542d2337d10c3e69063a5329011ef7082b6c7cec4d93232804ed00e40706b519f9874ebba7042d4c2ded2bba73553e5cda549d9131f3771f93376c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27656af763ca2a1f98be99dfc04e69c6f47b0bb54a15b5cf3d19ac1f888833fd515a6a10f923859b14fd5fb4939b40200634ed43e8a7fd59f96690f704972c05", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff19e24f5a7bbd67f1a68fe1a1f6e36502bac838b7752649ffc7ad86265af141f30942723eb9ff9300400e1d0fa2e4144b4bbbb80a6c3f4ea9a9f2f13a1153b2"} 14:09:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:09:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:09:15 INFO - Contents: 14:09:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5e06168a02e60ad9d85be54951b34fdd7bb08828dbe92cf3348872fbc57fdabcdfea93bb651941c964cf1d437364d9f3ade394adefb4374f01c93974c8b23c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c59f0fabc542d2337d10c3e69063a5329011ef7082b6c7cec4d93232804ed00e40706b519f9874ebba7042d4c2ded2bba73553e5cda549d9131f3771f93376c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27656af763ca2a1f98be99dfc04e69c6f47b0bb54a15b5cf3d19ac1f888833fd515a6a10f923859b14fd5fb4939b40200634ed43e8a7fd59f96690f704972c05", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff19e24f5a7bbd67f1a68fe1a1f6e36502bac838b7752649ffc7ad86265af141f30942723eb9ff9300400e1d0fa2e4144b4bbbb80a6c3f4ea9a9f2f13a1153b2"} 14:09:15 INFO - Running post-run listener: copy_logs_to_upload_dir 14:09:15 INFO - Copying logs to upload dir... 14:09:15 INFO - mkdir: /builds/slave/test/build/upload/logs 14:09:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.283561 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-10 14:09:15.898152) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-10 14:09:15.901682) ========= 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.fF3883ew3A/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.JfQcI5Fl1s/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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/b5e06168a02e60ad9d85be54951b34fdd7bb08828dbe92cf3348872fbc57fdabcdfea93bb651941c964cf1d437364d9f3ade394adefb4374f01c93974c8b23c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c59f0fabc542d2337d10c3e69063a5329011ef7082b6c7cec4d93232804ed00e40706b519f9874ebba7042d4c2ded2bba73553e5cda549d9131f3771f93376c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27656af763ca2a1f98be99dfc04e69c6f47b0bb54a15b5cf3d19ac1f888833fd515a6a10f923859b14fd5fb4939b40200634ed43e8a7fd59f96690f704972c05", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff19e24f5a7bbd67f1a68fe1a1f6e36502bac838b7752649ffc7ad86265af141f30942723eb9ff9300400e1d0fa2e4144b4bbbb80a6c3f4ea9a9f2f13a1153b2"} build_url:https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011229 build_url: 'https://queue.taskcluster.net/v1/task/LexJSiSSRwum_2sdKSVxBg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5e06168a02e60ad9d85be54951b34fdd7bb08828dbe92cf3348872fbc57fdabcdfea93bb651941c964cf1d437364d9f3ade394adefb4374f01c93974c8b23c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c59f0fabc542d2337d10c3e69063a5329011ef7082b6c7cec4d93232804ed00e40706b519f9874ebba7042d4c2ded2bba73553e5cda549d9131f3771f93376c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27656af763ca2a1f98be99dfc04e69c6f47b0bb54a15b5cf3d19ac1f888833fd515a6a10f923859b14fd5fb4939b40200634ed43e8a7fd59f96690f704972c05", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff19e24f5a7bbd67f1a68fe1a1f6e36502bac838b7752649ffc7ad86265af141f30942723eb9ff9300400e1d0fa2e4144b4bbbb80a6c3f4ea9a9f2f13a1153b2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-10 14:09:15.936831) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 14:09:15.937183) ========= 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.fF3883ew3A/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.JfQcI5Fl1s/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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.005143 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 14:09:15.986234) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-10 14:09:15.986572) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-10 14:09:15.986918) ========= ========= Total master_lag: 0.18 =========