builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0379 starttime: 1464394701.07 results: success (0) buildid: 20160527151144 builduid: 5f3e6f6a3dc94ab6a9ca5b77e66fbee2 revision: f8e493cd1e87a482c0246f4a8fe4229142bc1ccc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.069372) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.070220) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.070622) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.137304) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.137635) ========= 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.2beiD978Cp/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.XgAiCgAHvd/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 --2016-05-27 17:18:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 68.1M=0s 2016-05-27 17:18:21 (68.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.528443 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.700811) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.701086) ========= 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.2beiD978Cp/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.XgAiCgAHvd/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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.025069 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.756018) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 17:18:21.756323) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f8e493cd1e87a482c0246f4a8fe4229142bc1ccc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f8e493cd1e87a482c0246f4a8fe4229142bc1ccc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2beiD978Cp/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.XgAiCgAHvd/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 2016-05-27 17:18:21,823 truncating revision to first 12 chars 2016-05-27 17:18:21,823 Setting DEBUG logging. 2016-05-27 17:18:21,823 attempt 1/10 2016-05-27 17:18:21,823 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f8e493cd1e87?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 17:18:22,872 unpacking tar archive at: mozilla-aurora-f8e493cd1e87/testing/mozharness/ program finished with exit code 0 elapsedTime=1.313220 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 17:18:23.092746) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:23.093019) ========= script_repo_revision: f8e493cd1e87a482c0246f4a8fe4229142bc1ccc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:23.093383) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:23.093660) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:23.109598) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-05-27 17:18:23.109985) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2beiD978Cp/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.XgAiCgAHvd/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:18:23 INFO - MultiFileLogger online at 20160527 17:18:23 in /builds/slave/test 17:18:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:18:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:18:23 INFO - {'append_to_log': False, 17:18:23 INFO - 'base_work_dir': '/builds/slave/test', 17:18:23 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:18:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:18:23 INFO - 'branch': 'Mozilla-Aurora', 17:18:23 INFO - 'buildbot_json_path': 'buildprops.json', 17:18:23 INFO - 'config_files': ('talos/mac_config.py',), 17:18:23 INFO - 'default_actions': ('clobber', 17:18:23 INFO - 'read-buildbot-config', 17:18:23 INFO - 'download-and-extract', 17:18:23 INFO - 'populate-webroot', 17:18:23 INFO - 'create-virtualenv', 17:18:23 INFO - 'install', 17:18:23 INFO - 'run-tests'), 17:18:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:18:23 INFO - 'download_minidump_stackwalk': True, 17:18:23 INFO - 'download_symbols': 'ondemand', 17:18:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:18:23 INFO - 'tooltool.py': '/tools/tooltool.py', 17:18:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:18:23 INFO - '/tools/misc-python/virtualenv.py')}, 17:18:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:18:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:18:23 INFO - 'installer_path': 'installer.exe', 17:18:23 INFO - 'log_level': 'info', 17:18:23 INFO - 'log_name': 'talos', 17:18:23 INFO - 'log_to_console': True, 17:18:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:18:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:18:23 INFO - 'opt_config_files': (), 17:18:23 INFO - 'pip_index': False, 17:18:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:18:23 INFO - 'cmd': ('bash', 17:18:23 INFO - '-c', 17:18:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:18:23 INFO - 'enabled': True, 17:18:23 INFO - 'halt_on_failure': False, 17:18:23 INFO - 'name': 'check_screen_resolution'},), 17:18:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:18:23 INFO - 'cmd': ('bash', 17:18:23 INFO - '-c', 17:18:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:18:23 INFO - 'enabled': True, 17:18:23 INFO - 'halt_on_failure': False, 17:18:23 INFO - 'name': 'check_screen_resolution'},), 17:18:23 INFO - 'run_cmd_checks_enabled': True, 17:18:23 INFO - 'sps_profile': False, 17:18:23 INFO - 'sps_profile_interval': 0, 17:18:23 INFO - 'suite': 'dromaeojs-e10s', 17:18:23 INFO - 'system_bits': '32', 17:18:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:18:23 INFO - 'title': 't-yosemite-r7-0379', 17:18:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:18:23 INFO - 'use_talos_json': True, 17:18:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:18:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:18:23 INFO - 'work_dir': 'build'} 17:18:23 INFO - ##### 17:18:23 INFO - ##### Running clobber step. 17:18:23 INFO - ##### 17:18:23 INFO - Running pre-action listener: _resource_record_pre_action 17:18:23 INFO - Running main action method: clobber 17:18:23 INFO - rmtree: /builds/slave/test/build 17:18:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:18:25 INFO - Running post-action listener: _resource_record_post_action 17:18:25 INFO - ##### 17:18:25 INFO - ##### Running read-buildbot-config step. 17:18:25 INFO - ##### 17:18:25 INFO - Running pre-action listener: _resource_record_pre_action 17:18:25 INFO - Running main action method: read_buildbot_config 17:18:25 INFO - Using buildbot properties: 17:18:25 INFO - { 17:18:25 INFO - "project": "", 17:18:25 INFO - "product": "firefox", 17:18:25 INFO - "script_repo_revision": "production", 17:18:25 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 17:18:25 INFO - "repository": "", 17:18:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 17:18:25 INFO - "buildid": "20160527151144", 17:18:25 INFO - "pgo_build": "False", 17:18:25 INFO - "basedir": "/builds/slave/test", 17:18:25 INFO - "buildnumber": 13, 17:18:25 INFO - "slavename": "t-yosemite-r7-0379", 17:18:25 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 17:18:25 INFO - "platform": "yosemite_r7", 17:18:25 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 17:18:25 INFO - "branch": "mozilla-aurora", 17:18:25 INFO - "repo_path": "releases/mozilla-aurora", 17:18:25 INFO - "revision": "f8e493cd1e87a482c0246f4a8fe4229142bc1ccc", 17:18:25 INFO - "stage_platform": "macosx64", 17:18:25 INFO - "builduid": "5f3e6f6a3dc94ab6a9ca5b77e66fbee2", 17:18:25 INFO - "slavebuilddir": "test" 17:18:25 INFO - } 17:18:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 17:18:25 INFO - Running post-action listener: _resource_record_post_action 17:18:25 INFO - ##### 17:18:25 INFO - ##### Running download-and-extract step. 17:18:25 INFO - ##### 17:18:25 INFO - Running pre-action listener: _resource_record_pre_action 17:18:25 INFO - Running main action method: download_and_extract 17:18:25 INFO - mkdir: /builds/slave/test/build/tests 17:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:18:25 INFO - https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:18:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 17:18:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 17:18:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 17:18:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 17:18:41 INFO - Downloaded 1199 bytes. 17:18:41 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 17:18:41 INFO - Using the following test package requirements: 17:18:41 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 17:18:41 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:18:41 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 17:18:41 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:18:41 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 17:18:41 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:18:41 INFO - u'jsshell-mac.zip'], 17:18:41 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:18:41 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 17:18:41 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 17:18:41 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:18:41 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 17:18:41 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:18:41 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 17:18:41 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:18:41 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 17:18:41 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:18:41 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 17:18:41 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 17:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:18:41 INFO - https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:18:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:18:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:18:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:18:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:18:46 INFO - Downloaded 18425618 bytes. 17:18:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:18:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:18:49 INFO - Return code: 0 17:18:49 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 17:18:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:18:49 INFO - https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:18:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:18:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:18:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:18:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:18:53 INFO - Downloaded 18425618 bytes. 17:18:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:18:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:18:57 INFO - Return code: 0 17:18:57 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:18:57 INFO - https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:18:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 17:18:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 17:18:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 17:18:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 17:18:58 INFO - Downloaded 10840145 bytes. 17:18:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:18:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 17:18:58 INFO - Return code: 0 17:18:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:18:58 INFO - https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 17:18:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:18:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:18:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 17:18:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 17:19:01 INFO - Downloaded 114147676 bytes. 17:19:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:19:01 INFO - mkdir: /builds/slave/test/properties 17:19:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:19:01 INFO - Writing to file /builds/slave/test/properties/build_url 17:19:01 INFO - Contents: 17:19:01 INFO - build_url:https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:19:02 INFO - Running post-action listener: _resource_record_post_action 17:19:02 INFO - Running post-action listener: set_extra_try_arguments 17:19:02 INFO - ##### 17:19:02 INFO - ##### Running populate-webroot step. 17:19:02 INFO - ##### 17:19:02 INFO - Running pre-action listener: _resource_record_pre_action 17:19:02 INFO - Running main action method: populate_webroot 17:19:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:19:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:19:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:19:02 INFO - '--tppagecycles', 17:19:02 INFO - '7'], 17:19:02 INFO - 'tests': ['tsvgm']}}, 17:19:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:19:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:19:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:19:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:19:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:19:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:19:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:02 INFO - 'talos_options': ['--disable-e10s'], 17:19:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:19:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:19:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:02 INFO - 'talos_options': ['--disable-e10s'], 17:19:02 INFO - 'tests': ['damp', 'tps']}, 17:19:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:02 INFO - 'tests': ['damp', 'tps']}, 17:19:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:19:02 INFO - 'tests': ['dromaeo_dom']}, 17:19:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:19:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:19:02 INFO - 'tests': ['a11yr', 17:19:02 INFO - 'ts_paint', 17:19:02 INFO - 'tpaint', 17:19:02 INFO - 'sessionrestore', 17:19:02 INFO - 'sessionrestore_no_auto_restore', 17:19:02 INFO - 'tabpaint']}, 17:19:02 INFO - 'other-e10s': {'tests': ['a11yr', 17:19:02 INFO - 'ts_paint', 17:19:02 INFO - 'tpaint', 17:19:02 INFO - 'sessionrestore', 17:19:02 INFO - 'sessionrestore_no_auto_restore', 17:19:02 INFO - 'tabpaint']}, 17:19:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:19:02 INFO - 'ts_paint', 17:19:02 INFO - 'tpaint', 17:19:02 INFO - 'sessionrestore', 17:19:02 INFO - 'sessionrestore_no_auto_restore', 17:19:02 INFO - 'tabpaint']}, 17:19:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:19:02 INFO - 'ts_paint', 17:19:02 INFO - 'tpaint', 17:19:02 INFO - 'sessionrestore', 17:19:02 INFO - 'sessionrestore_no_auto_restore', 17:19:02 INFO - 'tabpaint']}, 17:19:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:19:02 INFO - 'tests': ['a11yr', 17:19:02 INFO - 'ts_paint', 17:19:02 INFO - 'tpaint', 17:19:02 INFO - 'sessionrestore', 17:19:02 INFO - 'sessionrestore_no_auto_restore', 17:19:02 INFO - 'tabpaint']}, 17:19:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:19:02 INFO - 'tests': ['a11yr', 17:19:02 INFO - 'ts_paint', 17:19:02 INFO - 'tpaint', 17:19:02 INFO - 'sessionrestore', 17:19:02 INFO - 'sessionrestore_no_auto_restore', 17:19:02 INFO - 'tabpaint']}, 17:19:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:19:02 INFO - 'tests': ['tsvgx', 17:19:02 INFO - 'tsvgr_opacity', 17:19:02 INFO - 'tart', 17:19:02 INFO - 'tscrollx', 17:19:02 INFO - 'cart']}, 17:19:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:19:02 INFO - 'tsvgr_opacity', 17:19:02 INFO - 'tart', 17:19:02 INFO - 'tscrollx', 17:19:02 INFO - 'cart']}, 17:19:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:02 INFO - 'talos_options': ['--disable-e10s'], 17:19:02 INFO - 'tests': ['tp5o']}, 17:19:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:02 INFO - 'tests': ['tp5o']}, 17:19:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:02 INFO - 'talos_options': ['--disable-e10s', 17:19:02 INFO - '--xperf_path', 17:19:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:19:02 INFO - 'tests': ['tp5n']}, 17:19:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:02 INFO - 'talos_options': ['--xperf_path', 17:19:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:19:02 INFO - 'tests': ['tp5n']}}, 17:19:02 INFO - 'talos.zip': {'path': '', 17:19:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:19:02 INFO - Running post-action listener: _resource_record_post_action 17:19:02 INFO - ##### 17:19:02 INFO - ##### Running create-virtualenv step. 17:19:02 INFO - ##### 17:19:02 INFO - Running pre-action listener: _resource_record_pre_action 17:19:02 INFO - Running main action method: create_virtualenv 17:19:02 INFO - Creating virtualenv /builds/slave/test/build/venv 17:19:02 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 17:19:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:19:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:19:02 INFO - Using real prefix '/tools/python27' 17:19:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:19:03 INFO - Installing distribute.............................................................................................................................................................................................done. 17:19:06 INFO - Installing pip.................done. 17:19:06 INFO - Return code: 0 17:19:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:19: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10275ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10276c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025fbe00>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025f6b20>, '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.2beiD978Cp/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.XgAiCgAHvd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2beiD978Cp/Render', 17:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:06 INFO - 'HOME': '/Users/cltbld', 17:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:06 INFO - 'LOGNAME': 'cltbld', 17:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:06 INFO - 'MOZ_NO_REMOTE': '1', 17:19:06 INFO - 'NO_EM_RESTART': '1', 17:19:06 INFO - 'PAGER': '/bin/cat', 17:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:06 INFO - 'PWD': '/builds/slave/test', 17:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:06 INFO - 'SHELL': '/bin/bash', 17:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XgAiCgAHvd/Listeners', 17:19:06 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:06 INFO - 'USER': 'cltbld', 17:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:06 INFO - 'XPC_FLAGS': '0x0', 17:19:06 INFO - 'XPC_SERVICE_NAME': '0', 17:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:06 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 17:19:06 INFO - Cleaning up... 17:19:06 INFO - Return code: 0 17:19:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:19: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10275ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10276c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025fbe00>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025f6b20>, '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.2beiD978Cp/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.XgAiCgAHvd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 17:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 17:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2beiD978Cp/Render', 17:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:06 INFO - 'HOME': '/Users/cltbld', 17:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:06 INFO - 'LOGNAME': 'cltbld', 17:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:06 INFO - 'MOZ_NO_REMOTE': '1', 17:19:06 INFO - 'NO_EM_RESTART': '1', 17:19:06 INFO - 'PAGER': '/bin/cat', 17:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:06 INFO - 'PWD': '/builds/slave/test', 17:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:06 INFO - 'SHELL': '/bin/bash', 17:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XgAiCgAHvd/Listeners', 17:19:06 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:06 INFO - 'USER': 'cltbld', 17:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:06 INFO - 'XPC_FLAGS': '0x0', 17:19:06 INFO - 'XPC_SERVICE_NAME': '0', 17:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:06 INFO - Downloading/unpacking psutil>=0.7.1 17:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:06 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 17:19:06 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 17:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:19:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:19:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:19:10 INFO - Installing collected packages: psutil 17:19:10 INFO - Running setup.py install for psutil 17:19:10 INFO - building 'psutil._psutil_osx' extension 17:19:10 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 17:19:10 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 17:19:11 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 17:19:11 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 17:19:11 INFO - building 'psutil._psutil_posix' extension 17:19:11 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 17:19:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:19:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:19:11 INFO - ^ 17:19:11 INFO - 1 warning generated. 17:19:11 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 17:19:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:19:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:19:11 INFO - Successfully installed psutil 17:19:11 INFO - Cleaning up... 17:19:11 INFO - Return code: 0 17:19:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:19:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10275ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10276c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025fbe00>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025f6b20>, '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.2beiD978Cp/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.XgAiCgAHvd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 17:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 17:19:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2beiD978Cp/Render', 17:19:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:11 INFO - 'HOME': '/Users/cltbld', 17:19:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:11 INFO - 'LOGNAME': 'cltbld', 17:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:11 INFO - 'MOZ_NO_REMOTE': '1', 17:19:11 INFO - 'NO_EM_RESTART': '1', 17:19:11 INFO - 'PAGER': '/bin/cat', 17:19:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:11 INFO - 'PWD': '/builds/slave/test', 17:19:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:11 INFO - 'SHELL': '/bin/bash', 17:19:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XgAiCgAHvd/Listeners', 17:19:11 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:19:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:11 INFO - 'USER': 'cltbld', 17:19:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:11 INFO - 'XPC_FLAGS': '0x0', 17:19:11 INFO - 'XPC_SERVICE_NAME': '0', 17:19:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:14 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:19:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:19:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:19:14 INFO - Installing collected packages: mozsystemmonitor 17:19:14 INFO - Running setup.py install for mozsystemmonitor 17:19:14 INFO - Successfully installed mozsystemmonitor 17:19:14 INFO - Cleaning up... 17:19:14 INFO - Return code: 0 17:19:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:19:14 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:14 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:14 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 0x10275ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10276c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025fbe00>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025f6b20>, '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.2beiD978Cp/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.XgAiCgAHvd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:19:14 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 17:19:14 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 17:19:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2beiD978Cp/Render', 17:19:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:14 INFO - 'HOME': '/Users/cltbld', 17:19:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:14 INFO - 'LOGNAME': 'cltbld', 17:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:14 INFO - 'MOZ_NO_REMOTE': '1', 17:19:14 INFO - 'NO_EM_RESTART': '1', 17:19:14 INFO - 'PAGER': '/bin/cat', 17:19:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:14 INFO - 'PWD': '/builds/slave/test', 17:19:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:14 INFO - 'SHELL': '/bin/bash', 17:19:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XgAiCgAHvd/Listeners', 17:19:14 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:19:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:14 INFO - 'USER': 'cltbld', 17:19:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:14 INFO - 'XPC_FLAGS': '0x0', 17:19:14 INFO - 'XPC_SERVICE_NAME': '0', 17:19:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:14 INFO - Downloading/unpacking blobuploader==1.2.4 17:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:17 INFO - Downloading blobuploader-1.2.4.tar.gz 17:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:19:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:19:17 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 17:19:17 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 17:19:17 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 17:19:17 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 17:19:17 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 17:19:17 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 17:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:19:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:19:17 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 17:19:17 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 17:19:17 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 17:19:17 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 17:19:17 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 17:19:17 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 17:19:18 INFO - Downloading docopt-0.6.1.tar.gz 17:19:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:19:18 INFO - Installing collected packages: blobuploader, requests, docopt 17:19:18 INFO - Running setup.py install for blobuploader 17:19:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:19:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:19:18 INFO - Running setup.py install for requests 17:19:19 INFO - Running setup.py install for docopt 17:19:19 INFO - Successfully installed blobuploader requests docopt 17:19:19 INFO - Cleaning up... 17:19:19 INFO - Return code: 0 17:19:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:19:19 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:19 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:19 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 0x10275ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10276c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025fbe00>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025f6b20>, '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.2beiD978Cp/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.XgAiCgAHvd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:19:19 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 17:19:19 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 17:19:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2beiD978Cp/Render', 17:19:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:19 INFO - 'HOME': '/Users/cltbld', 17:19:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:19 INFO - 'LOGNAME': 'cltbld', 17:19:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:19 INFO - 'MOZ_NO_REMOTE': '1', 17:19:19 INFO - 'NO_EM_RESTART': '1', 17:19:19 INFO - 'PAGER': '/bin/cat', 17:19:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:19 INFO - 'PWD': '/builds/slave/test', 17:19:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:19 INFO - 'SHELL': '/bin/bash', 17:19:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XgAiCgAHvd/Listeners', 17:19:19 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:19:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:19 INFO - 'USER': 'cltbld', 17:19:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:19 INFO - 'XPC_FLAGS': '0x0', 17:19:19 INFO - 'XPC_SERVICE_NAME': '0', 17:19:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:19:19 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-QmWsC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:19:19 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-0oXJLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:19:19 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-w_YO8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:19:19 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-5t5Av6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:19:20 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-NUhE8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:19:20 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-dWsnj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:19:20 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-v7Lpvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:19:20 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-QxvGOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:19:20 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-zOu9Ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:19:20 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-hFF9Xl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:19:20 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-e3btd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:19:20 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-m8iw84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:19:20 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-RpTFqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:19:20 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-E8ezB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:19:21 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-iUBlRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:19:21 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-93Kq6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:19:21 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-3KkOa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:19:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:19:21 INFO - Running setup.py install for manifestparser 17:19:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:19:21 INFO - Running setup.py install for mozcrash 17:19:21 INFO - Running setup.py install for mozdebug 17:19:21 INFO - Running setup.py install for mozdevice 17:19:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:19:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:19:22 INFO - Running setup.py install for mozfile 17:19:22 INFO - Running setup.py install for mozhttpd 17:19:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:19:22 INFO - Running setup.py install for mozinfo 17:19:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:19:22 INFO - Running setup.py install for mozInstall 17:19:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:19:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:19:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:19:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:19:22 INFO - Running setup.py install for mozleak 17:19:22 INFO - Running setup.py install for mozlog 17:19:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:19:22 INFO - Running setup.py install for moznetwork 17:19:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:19:23 INFO - Running setup.py install for mozprocess 17:19:23 INFO - Running setup.py install for mozprofile 17:19:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:19:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:19:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:19:23 INFO - Running setup.py install for mozrunner 17:19:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:19:23 INFO - Running setup.py install for mozscreenshot 17:19:23 INFO - Running setup.py install for moztest 17:19:23 INFO - Running setup.py install for mozversion 17:19:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:19:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:19:23 INFO - Cleaning up... 17:19:23 INFO - Return code: 0 17:19:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:19:23 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:23 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:23 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 0x10275ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10276c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025fbe00>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025f6b20>, '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.2beiD978Cp/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.XgAiCgAHvd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:19:23 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 17:19:23 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 17:19:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2beiD978Cp/Render', 17:19:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:23 INFO - 'HOME': '/Users/cltbld', 17:19:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:23 INFO - 'LOGNAME': 'cltbld', 17:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:23 INFO - 'MOZ_NO_REMOTE': '1', 17:19:23 INFO - 'NO_EM_RESTART': '1', 17:19:23 INFO - 'PAGER': '/bin/cat', 17:19:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:23 INFO - 'PWD': '/builds/slave/test', 17:19:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:23 INFO - 'SHELL': '/bin/bash', 17:19:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XgAiCgAHvd/Listeners', 17:19:23 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:19:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:23 INFO - 'USER': 'cltbld', 17:19:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:23 INFO - 'XPC_FLAGS': '0x0', 17:19:23 INFO - 'XPC_SERVICE_NAME': '0', 17:19:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:19:24 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-nKH2hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:19:24 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)) 17:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:19:24 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-tSbENi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 17:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:19:24 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-zDXhnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:19:24 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)) 17:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:19:24 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-YmLsuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 17:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:19:24 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-xe1STg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:19:24 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)) 17:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:19:24 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-CMR7WF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:19:24 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)) 17:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:19:24 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-1cjTMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:19:24 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)) 17:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:19:24 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-xNufI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:19:25 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)) 17:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:19:25 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-8YC76G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:19:25 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)) 17:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:19:25 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-GwMYy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 17:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:19:25 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-IqEgXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:19:25 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)) 17:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:19:25 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-alK9_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 17:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:19:25 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-zwBlrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:19:25 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)) 17:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:19:25 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-22_XBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 17:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:19:25 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-j2De2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:19:25 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)) 17:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:19:25 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-YiRIKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 17:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:19:25 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-0cfzn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:19:26 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)) 17:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:19:26 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)) 17:19:26 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:19:26 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:19:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:19:26 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 17:19:26 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 17:19:26 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 17:19:26 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 17:19:26 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 17:19:26 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 17:19:28 INFO - Downloading blessings-1.6.tar.gz 17:19:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:19:28 INFO - Installing collected packages: blessings 17:19:28 INFO - Running setup.py install for blessings 17:19:28 INFO - Successfully installed blessings 17:19:28 INFO - Cleaning up... 17:19:28 INFO - Return code: 0 17:19:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:19:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:19:29 INFO - Reading from file tmpfile_stdout 17:19:29 INFO - Current package versions: 17:19:29 INFO - blessings == 1.6 17:19:29 INFO - blobuploader == 1.2.4 17:19:29 INFO - docopt == 0.6.1 17:19:29 INFO - manifestparser == 1.1 17:19:29 INFO - mozInstall == 1.12 17:19:29 INFO - mozcrash == 0.17 17:19:29 INFO - mozdebug == 0.1 17:19:29 INFO - mozdevice == 0.48 17:19:29 INFO - mozfile == 1.2 17:19:29 INFO - mozhttpd == 0.7 17:19:29 INFO - mozinfo == 0.9 17:19:29 INFO - mozleak == 0.1 17:19:29 INFO - mozlog == 3.1 17:19:29 INFO - moznetwork == 0.27 17:19:29 INFO - mozprocess == 0.22 17:19:29 INFO - mozprofile == 0.28 17:19:29 INFO - mozrunner == 6.11 17:19:29 INFO - mozscreenshot == 0.1 17:19:29 INFO - mozsystemmonitor == 0.0 17:19:29 INFO - moztest == 0.7 17:19:29 INFO - mozversion == 1.4 17:19:29 INFO - psutil == 3.1.1 17:19:29 INFO - requests == 1.2.3 17:19:29 INFO - wsgiref == 0.1.2 17:19:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:19:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:29 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 0x10275ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10276c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025fbe00>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025f6b20>, '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.2beiD978Cp/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.XgAiCgAHvd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:19:29 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 17:19:29 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 17:19:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2beiD978Cp/Render', 17:19:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:29 INFO - 'HOME': '/Users/cltbld', 17:19:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:29 INFO - 'LOGNAME': 'cltbld', 17:19:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:29 INFO - 'MOZ_NO_REMOTE': '1', 17:19:29 INFO - 'NO_EM_RESTART': '1', 17:19:29 INFO - 'PAGER': '/bin/cat', 17:19:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:29 INFO - 'PWD': '/builds/slave/test', 17:19:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:29 INFO - 'SHELL': '/bin/bash', 17:19:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XgAiCgAHvd/Listeners', 17:19:29 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:19:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:29 INFO - 'USER': 'cltbld', 17:19:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:29 INFO - 'XPC_FLAGS': '0x0', 17:19:29 INFO - 'XPC_SERVICE_NAME': '0', 17:19:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:29 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)) 17:19:29 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)) 17:19:29 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)) 17:19:29 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)) 17:19:29 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)) 17:19:29 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)) 17:19:29 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)) 17:19:29 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)) 17:19:29 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)) 17:19:29 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)) 17:19:29 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)) 17:19:29 INFO - Cleaning up... 17:19:29 INFO - Return code: 0 17:19:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:19:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:29 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 0x10275ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10276c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025fbe00>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025f6b20>, '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.2beiD978Cp/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.XgAiCgAHvd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:19:29 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 17:19:29 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 17:19:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2beiD978Cp/Render', 17:19:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:29 INFO - 'HOME': '/Users/cltbld', 17:19:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:29 INFO - 'LOGNAME': 'cltbld', 17:19:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:29 INFO - 'MOZ_NO_REMOTE': '1', 17:19:29 INFO - 'NO_EM_RESTART': '1', 17:19:29 INFO - 'PAGER': '/bin/cat', 17:19:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:29 INFO - 'PWD': '/builds/slave/test', 17:19:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:29 INFO - 'SHELL': '/bin/bash', 17:19:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XgAiCgAHvd/Listeners', 17:19:29 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:19:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:29 INFO - 'USER': 'cltbld', 17:19:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:29 INFO - 'XPC_FLAGS': '0x0', 17:19:29 INFO - 'XPC_SERVICE_NAME': '0', 17:19:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:30 INFO - Downloading/unpacking jsonschema 17:19:30 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 17:19:30 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 17:19:30 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 17:19:30 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 17:19:30 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 17:19:30 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 17:19:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:19:32 INFO - Downloading/unpacking functools32 (from jsonschema) 17:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:32 INFO - Downloading functools32-3.2.3-2.tar.gz 17:19:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:19:33 INFO - warning: no files found matching '*.txt' 17:19:33 INFO - no previously-included directories found matching 'build' 17:19:33 INFO - no previously-included directories found matching 'dist' 17:19:33 INFO - no previously-included directories found matching '.git*' 17:19:33 INFO - Installing collected packages: jsonschema, functools32 17:19:33 INFO - Running setup.py install for functools32 17:19:33 INFO - warning: no files found matching '*.txt' 17:19:33 INFO - no previously-included directories found matching 'build' 17:19:33 INFO - no previously-included directories found matching 'dist' 17:19:33 INFO - no previously-included directories found matching '.git*' 17:19:33 INFO - Successfully installed jsonschema functools32 17:19:33 INFO - Cleaning up... 17:19:33 INFO - Return code: 0 17:19:33 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:19:33 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:33 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:33 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10275ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10276c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025fbe00>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025f6b20>, '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.2beiD978Cp/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.XgAiCgAHvd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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 17:19:33 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', 'flake8'] in /builds/slave/test/build 17:19:33 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 flake8 17:19:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2beiD978Cp/Render', 17:19:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:33 INFO - 'HOME': '/Users/cltbld', 17:19:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:33 INFO - 'LOGNAME': 'cltbld', 17:19:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:33 INFO - 'MOZ_NO_REMOTE': '1', 17:19:33 INFO - 'NO_EM_RESTART': '1', 17:19:33 INFO - 'PAGER': '/bin/cat', 17:19:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:33 INFO - 'PWD': '/builds/slave/test', 17:19:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:33 INFO - 'SHELL': '/bin/bash', 17:19:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XgAiCgAHvd/Listeners', 17:19:33 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:19:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:33 INFO - 'USER': 'cltbld', 17:19:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:33 INFO - 'XPC_FLAGS': '0x0', 17:19:33 INFO - 'XPC_SERVICE_NAME': '0', 17:19:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:33 INFO - Downloading/unpacking flake8 17:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:33 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 17:19:33 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 17:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:19:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:19:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:19:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:19:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:19:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:19:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:19:37 INFO - no previously-included directories found matching 'docs/_build' 17:19:37 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:19:37 INFO - Running setup.py install for pep8 17:19:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:19:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:19:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:19:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:19:37 INFO - no previously-included directories found matching 'docs/_build' 17:19:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:19:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:19:37 INFO - Cleaning up... 17:19:37 INFO - Return code: 0 17:19:37 INFO - Running post-action listener: _resource_record_post_action 17:19:37 INFO - Running post-action listener: _start_resource_monitoring 17:19:37 INFO - Starting resource monitoring. 17:19:37 INFO - ##### 17:19:37 INFO - ##### Running install step. 17:19:37 INFO - ##### 17:19:37 INFO - Running pre-action listener: _resource_record_pre_action 17:19:37 INFO - Running main action method: install 17:19:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:19:37 INFO - Reading from file tmpfile_stdout 17:19:37 INFO - Detecting whether we're running mozinstall >=1.0... 17:19:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:19:38 INFO - Reading from file tmpfile_stdout 17:19:38 INFO - Output received: 17:19:38 INFO - Usage: mozinstall [options] installer 17:19:38 INFO - Options: 17:19:38 INFO - -h, --help show this help message and exit 17:19:38 INFO - -d DEST, --destination=DEST 17:19:38 INFO - Directory to install application into. [default: 17:19:38 INFO - "/builds/slave/test"] 17:19:38 INFO - --app=APP Application being installed. [default: firefox] 17:19:38 INFO - mkdir: /builds/slave/test/build/application 17:19:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:19:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 17:20:09 INFO - Reading from file tmpfile_stdout 17:20:09 INFO - Output received: 17:20:09 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:20:09 INFO - Running post-action listener: _resource_record_post_action 17:20:09 INFO - ##### 17:20:09 INFO - ##### Running run-tests step. 17:20:09 INFO - ##### 17:20:09 INFO - Running pre-action listener: _resource_record_pre_action 17:20:09 INFO - Running main action method: run_tests 17:20:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:20:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:20:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:20:09 INFO - 2016-05-27 17:20:09.762 screenresolution[1648:8589] starting screenresolution argv=screenresolution get 17:20:09 INFO - 2016-05-27 17:20:09.765 screenresolution[1648:8589] Display 0: 1600x1200x32@60 17:20:09 INFO - 2016-05-27 17:20:09.771 screenresolution[1649:8591] starting screenresolution argv=screenresolution list 17:20:09 INFO - Available Modes on Display 0 17:20:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:20:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:20:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:20:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:20:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:20:09 INFO - Intel Iris: 17:20:09 INFO - Chipset Model: Intel Iris 17:20:09 INFO - Type: GPU 17:20:09 INFO - Bus: Built-In 17:20:09 INFO - VRAM (Dynamic, Max): 1536 MB 17:20:09 INFO - Vendor: Intel (0x8086) 17:20:09 INFO - Device ID: 0x0a2e 17:20:10 INFO - Revision ID: 0x0009 17:20:10 INFO - Displays: 17:20:10 INFO - Display: 17:20:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:20:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:20:10 INFO - Display Serial Number: 1600x1200 60 17:20:10 INFO - Main Display: Yes 17:20:10 INFO - Mirror: Off 17:20:10 INFO - Online: Yes 17:20:10 INFO - Rotation: Supported 17:20:10 INFO - Return code: 0 17:20:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:20:10 INFO - Python 2.7.3 17:20:10 INFO - Return code: 0 17:20:10 INFO - grabbing minidump binary from tooltool 17:20:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:10 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025fbe00>, '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 0x102464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1025f6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:20:10 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 17:20:10 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 17:20:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:20:10 INFO - Return code: 0 17:20:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:20:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:20:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:20:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:20:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:20:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:20:11 INFO - Return code: 0 17:20:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--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 17:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0379 --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 17:20:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2beiD978Cp/Render', 17:20:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:11 INFO - 'HOME': '/Users/cltbld', 17:20:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:11 INFO - 'LOGNAME': 'cltbld', 17:20:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:20:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:11 INFO - 'MOZ_NO_REMOTE': '1', 17:20:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:20:11 INFO - 'NO_EM_RESTART': '1', 17:20:11 INFO - 'PAGER': '/bin/cat', 17:20:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:11 INFO - 'PWD': '/builds/slave/test', 17:20:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:20:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:11 INFO - 'SHELL': '/bin/bash', 17:20:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XgAiCgAHvd/Listeners', 17:20:11 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 17:20:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:11 INFO - 'USER': 'cltbld', 17:20:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:11 INFO - 'XPC_FLAGS': '0x0', 17:20:11 INFO - 'XPC_SERVICE_NAME': '0', 17:20:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--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 17:20:11 INFO - mozversion application_buildid: 20160527151144 17:20:11 INFO - mozversion application_changeset: f8e493cd1e87a482c0246f4a8fe4229142bc1ccc 17:20:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:20:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:20:11 INFO - mozversion application_name: Firefox 17:20:11 INFO - mozversion application_remotingname: firefox-dev 17:20:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:20:11 INFO - mozversion application_vendor: Mozilla 17:20:11 INFO - mozversion application_version: 48.0a2 17:20:11 INFO - mozversion platform_buildid: 20160527151144 17:20:11 INFO - mozversion platform_changeset: f8e493cd1e87a482c0246f4a8fe4229142bc1ccc 17:20:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:20:11 INFO - mozversion platform_version: 48.0a2 17:20:11 INFO - using testdate: 1464394811 17:20:11 INFO - actual date: 1464394811 17:20:11 INFO - starting webserver on 'localhost:49270' 17:20:11 INFO - SUITE-START | Running 2 tests 17:20:11 INFO - TEST-START | dromaeo_css 17:20:11 INFO - operating with platform_type : mac_ 17:20:11 INFO - Initialising browser for dromaeo_css test... 17:20:11 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmp2Uw5pB/profile http://localhost:49270/getInfo.html) 17:20:14 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 17:20:14 INFO - PROCESS | 1667 | colorDepth:24 17:20:14 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 17:20:14 INFO - PROCESS | 1667 | __metrics 17:20:14 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 17:20:14 INFO - TEST-INFO | 1667: exit 0 17:20:14 INFO - Browser initialized. 17:20:14 INFO - Running cycle 1/1 for dromaeo_css test... 17:20:14 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmp2Uw5pB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:20:27 INFO - PROCESS | 1671 | RSS: Main: 220905472 17:20:27 INFO - PROCESS | 1671 | 17:21:16 INFO - PROCESS | 1671 | 1464394876657 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 17:23:27 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 17:23:29 INFO - PROCESS | 1671 | RSS: Main: 231579648 17:23:29 INFO - PROCESS | 1671 | 17:26:31 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 17:26:31 INFO - PROCESS | 1671 | RSS: Main: 243167232 17:26:31 INFO - PROCESS | 1671 | 17:29:33 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 17:29:34 INFO - PROCESS | 1671 | RSS: Main: 245321728 17:29:34 INFO - PROCESS | 1671 | 17:32:35 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 17:32:36 INFO - PROCESS | 1671 | RSS: Main: 246874112 17:32:36 INFO - PROCESS | 1671 | 17:35:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 17:35:39 INFO - PROCESS | 1671 | RSS: Main: 250228736 17:35:39 INFO - PROCESS | 1671 | 17:38:42 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 17:38:42 INFO - PROCESS | 1671 | RSS: Main: 250896384 17:38:42 INFO - PROCESS | 1671 | 17:38:42 INFO - PROCESS | 1671 | __start_tp_report 17:38:42 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 17:38:42 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 17:38:42 INFO - PROCESS | 1671 | |i|pagename|runs| 17:38:42 INFO - PROCESS | 1671 | |0;dojo.html;2839.389531250781;2976.8213907165587;3502.63222361652;3533.982330088349;3565.750397472177;5815.709214539267;5823.126531020349;5840.007198776202;5860.120981852713;5886.440788125121;5806.361300256968;5825.8252252432385;5884.588078834483;5916.585838991272;5967.254093238345;4438.4895736990175;5246.973765131179;5304.681719096847;5352.866178345534;5401.4328495508;5134.897302053957;5293.364796224459;5295.4704529547125;5301.549368303698;5316.946830531703;4314.568543145691;5312.256284120207;5314.92027619586;5319.574434045287;5329;5225.581953443734;5301.7084060376665;5309.150535914235;5345.385280692732;5385.138377859563;4396.868093957154;4406.955930440687;5251.422343542207;5329.413764485904;5393.7572809223675;5323.733813309319;5557.888842223133;5711.543076553866;5859.560532960045;5894.616849904743;6165.229346331708;6214;6224.502039836848;6242.812715618494;6278.183836101278;6267.279262884781;6268.122462855204;6273.843153921142;6297.149884765589;6306.747730090791;4835.734034628129;5857.15071314657;5974.761009559613;6017.42834430724;6053.092036194562;6155.815325540151;6197.25632924052;6207.193064901534;6215.129881816459;6227.283862355796;5903.616264942765;5919.763209471659;5932;5962.135490353874;5995.190649262294;6077.36187700294;6137.386261373917;6221.2845522764555;6225.595336303217;6279.340669229665;5448.972755136199;6277.058441233851;6311.084892690534;6327.588706734047;6348.206474190726;6075.544334174954;6185.310756932475;6320.873582528324;6322;6326.43062124411;4914.385701787277;4942.604591632661;4956.900861982812;5012.172991456442;5052.848414547496;4478.149151661174;4482.148391805546;4489.6408287336935;4495.303227999665;4531.410916580823;5655.095184770499;5864.941350586525;5884.941150588439;5906.822795316148;5915.231019967377;5363.302770639792;6203.689815509207;6214.658193799294;6278.717457714414;6302.054691796267;4795.6163506919365;5677.120046392815;5851.64890106595;5872.500837428866;5893.705314734332;4572.45130584332;4645.581897629229;5513.145462453326;5520.1167813149705;5542.002439560918;5461.863453413696;6080.209572755513;6100.847478813065;6381.553291269659;6418.679066046679;6256.405641464054;6257.9687101564205;6291.307956124914;6294.968525157528;6317.241930968313;6104.331286938502;6466.870662586628;6474.449671777952;6477.773277935438;6578.809277500312;6094.481969032682;6150.692465376624;6243.907316219553;6263.404976527405;6291.182146320949;6247.656378899204;6276.686165691788;6277.686115694289;6287.119803227643;6297.275813281532;6057.969710151421;6058.091286307089;6234.594751341148;6287.371262873676;6291.591046581958;5306.734663266744;6202.844928876814;6250.3749625037135;6259.092431597484;6263.874722505616;5966.284045914518;5998.400159984141;6015.39846015395;6053.364396738279;6087.4521293083835;6023.45788879003;6112.41229444207;6192.102145188832;6233.127362169209;6247.531435142291;6053.969730151321;6081.756729730761;6096.725647345791;6106.267247930276;6120.782376608402 17:38:42 INFO - PROCESS | 1671 | |1;ext.html;496.79134763399344;512.2955935588566;541.5748637319705;541.8080223508283;563.4253061876885;38893.80553097234;39809.805705828796;39856.003599910015;39858.604948826796;40029.39955900664;31979.36041279173;41657;41681.166376672445;41699.79150104253;41979.95050123749;8330.62512186953;8384.538850363213;9526.380785248937;9748;9755.512224388787;9530.14228719415;9695.951520242388;9769.120779129877;9776.755581110458;9791.706248812512;9692.90307096927;9747.463889486075;9782.413055216673;9794.902050979506;9805.36265142767;9731.124198822105;9795.804083918352;9797.216222702167;9799.755006124868;9818.65634702789;8152.2662960333855;8230.547319897403;9492.525373731354;9601.231901447867;9722.756931076708;33093.503597446055;38531.036724081845;39669.40495892564;39816.800915995525;40188.79905600483;3171.302313491028;3482.564679415073;3491.825408729553;3776.0937375029794;3805.5052843130493;29076.70923290761;29144.70855291441;29184.41631167365;29313.974010909515;29411;2757.1866299441713;2759.765419939308;2777.6389069420848;2782.5826126081047;2817.422428402193;456056;458221;461035;461638;462748;65031;65361.346386536476;65637;65669;65673.34326656672;69319.30680693129;69711.30288697149;70995;73108.63445682738;73249.26750732424;17294.048827314367;17814.821851781577;17842.375516856846;18005.549861253574;18085.914845109335;13823.79264311036;13853.16880987144;13876.375563099684;13952.651183720489;13992;16765.24556394965;19830;20137.899310503355;20145.496362591053;20149.39551813448;8002.759917202493;8184.877226841602;8232.50604963704;8240.876386854203;8291.378146639026;974.6296407365297;1022.7903279827617;1028.5525796278644;1057.4765491081964;1074.258761454594;30562.38875222483;30926.381472370427;31158.844205778827;31209.219769505944;31380.37239255202;1120.2550304047718;1121.8036843552345;1141.2866958151155;1191.2197510630551;1247.5384107880143;14659.193744344164;15803;15825.522171394816;15992.280347384163;16640;615704;616339;618564;623187;625889;60970.0854487183;62442;62582.748345032844;63331;63471;1493.9775903361458;1515.2878147270544;1515.3256800723793;1517.7343964806114;1521.6956608678308;29906.551401729426;30322.69677303199;30818.691813082478;30871.691283086882;31534.369312613162;39238.80380598079;41617.75146745602;55370;55658;55659;541893;551773;554097.4590254045;555011;557586;27810.860945695953;28211.71788282091;36127.819360904075;43718.344224836015;44916.55083449124;43557.3466397998;44081;44110.11779764451;44999.550004499535;45056.77471612621;32182.517262240617;32243.549040292775;38326.23347532978;40014.79992600018;42749;31452.842735786173;35705.64294357127;36528.26943461169;36844.44733329056;36853.63146368502;36910;38674.41988370233;38777.4183387255;39601.801990991014;40343.79828100958;32425;33301.83349083239;33911.660883391836;34075;34184.316313674084;24684.753152468962;25073.749262507143;25272.62091068672;25431.87284063568;25917.352066198495 17:38:42 INFO - PROCESS | 1671 | |2;jquery.html;381.9809009549523;400.9879703608891;401.39389521822056;401.8834537983985;406.38432774346853;14702.233866245893;16280.430184943529;20007.19971201152;20743.792562074374;20813;16472.011679299252;19708.605827883435;20515.794842051575;20516.692249616266;20567.897160514214;5296.735163241842;5453.727313634322;5467.261919640838;5473.28847249859;5478.397376288605;5118.436971933084;5375.81184658537;5379.704116273603;5380.515753582177;5387.757550910199;5241.711705856176;5326.973365133169;5340.786368545254;5369.919451208235;5388.703621300827;4628.236341003731;5332.093544097494;5798.7100644968;5937;5950.047992321208;5682.346530149044;5736.627119237236;5768.682722450264;5787.50806181475;5788.305403351625;13274.66813329665;13619.978501612319;13657.453701851913;13663.795043074262;22228.555428891494;2145.903434345458;2209.4476380904775;2216.0581752755015;2289.6336586146135;2301.309607117858;9508.667196648154;9720.951395242979;9822.361546499526;10120.544575494121;10325.277230593862;1434.7274017936663;1728.265487167961;1728.3777839977597;1749.615084681368;1774.299151835031;96871.51564242205;106026;109270.90729092687;113162.86837131606;116466;11817.40465037209;12003.519859205533;12045.578404755792;12074.456649450796;12113.818292725617;15263.007904486172;15311.923440382727;15328.003679760779;15331.080135191702;15365.001274917318;8909.910900890907;8953.59708813105;8998.910010899937;9060.411073280347;9118.042605526456;8989.910100899038;9009.459432434074;9051.27589792815;9115.544222788834;9143;4064.4106604542176;4633.907321853544;4724.456687480915;4734.218853889139;4778.474367819537;1994.4914046917943;2001.2295266322571;2019.6061767955403;2026.625074361248;2070.3478404302596;797.6849144587856;825.4469505431375;897.2866571076011;1004.2267454060333;1022.2435397805571;19719.30982415637;20311.593768124556;20458.59082818365;20602;20693.27573534919;959.64972784933;984.542187882638;1006.9395836249848;1007.2093406675724;1026.1841835740604;10714.410707411167;11090.445477726082;11099.889001109885;11121.610743623934;11139.721506962229;582931;593000;600015.9999199976;605486;607122;112182;113505;114025;115414;115848.42075789736;826.68999125328;971.7327734872966;975.4196253229317;983.0857302708549;1030.8144533983955;14736.48422305214;17774;17865.910670446563;18012.36956706509;18096.914185148933;47609.76195119002;48803.26795098148;49854;49973.500264998336;50358.2446263299;70516.64741676259;72254;72518.27481725326;73607.52784944378;73792.89310660452;8220.465669731448;8246.299064579338;8281.29608983243;8332.416730828783;8519.659213631634;12241.632751017483;12514.812277816021;12697.36513174319;12871.22772633645;12902.483900643869;8256.463329883538;8423.410361274651;10618.8938110621;10784.514696838818;11260.605878794531;9253.768655783659;9266.768330791785;10622.309549879234;10774.838377424188;10786.298890572089;5609.467100625434;7947.920520794718;8474.067852536093;10282.794344113225;10759.30064545802;8225.83548329018;8236.258736713724;8320.958395207985;8462.19609137131;8630.741077767678;10550.577976880839;10642.467876605984;11067.501962411872;11303.208775385972;11309.886901130883 17:38:42 INFO - PROCESS | 1671 | |3;mootools.html;1365.7473367427024;1596.8901613378703;1781.8930864148151;1899.4776436479967;1944.3389247655793;7587.4688771785995;7996.680132794696;8567.742967710963;8716.259117974972;8901.777455563606;8630.784230394243;8665.826683466326;8734.563271836414;8749.95625021874;8758.956205218965;2422.491276831863;2430.720467146274;2439.280412278373;2440.4752978109705;2442.65802787609;2418.504206637635;2422.588160012802;2460.4709987352717;2466.519028789387;2480.9751902481016;2424.7270183153896;2440.316711320833;2455.0793452455327;2461.90152393904;2468.7037555493366;2433.3794882305074;2448.4368595223023;2454.435479839638;2463.5072985402903;2463.8521688698734;2448.9633054520514;2450.656908032877;2466.2477944227003;2468.8148388870904;2479.442125521761;8918.464892106429;8946.64213431462;8951.14964078418;8975.730728078168;8977.551122443852;5348.652337598043;5474.042042642522;5491.2312276281355;5520.337559492847;5538.889222215533;2957.349383135707;3454.136465883529;3545.0428384336083;3606.6934310583633;3625.1843335249623;1010.7271036820091;1020.5254556631202;1030.8196065688473;1036.9325993810378;1052.1372474570856;11711;11726.120540959462;11856.762864742657;11857.110716696283;11892.167548271624;4706.882327941828;4776.99366266169;4854.854354369375;4860.270959356125;4861.878453038632;10029.899701002896;10113;10121.987801219819;10164.390136591828;10192.184625229964;5408.3509978802795;5421.186821976735;5445.727713614304;5447.509724124848;5472.179173123984;4396.868093957187;4850.53919877611;4919.3604831371695;4973;5029.446760856303;3379.5437615921537;4051.554329023805;4098.159877225162;4147.647449966787;4175.227582897173;1156.6125348008343;1354.3769865861814;1379.1793882639813;1385.5912505810609;1386.0782579584463;798.8002999250187;921.5576523268735;930.251147825993;931.5342328835583;931.9813603727888;5558.44415558441;5855.912161317584;5936.406359364116;5995.040793473024;6082.026875699867;931.3899395895563;935.3919952031098;967.656481948898;990.9752256193652;995.3231802374455;3767.434884767307;3769.8115094245727;3864.2851072551666;3872.0513474199;3947.881563553098;10250.282480226462;10454.52954617044;10503.422311772772;10535.736606584744;10570.312929659547;10207.285490015776;10407.271490995558;10417.5832966682;10433;10452.216083793746;7001.229864714877;7706.537607743553;8372.455790373728;8444.240018398374;8456.915430845736;5780.855478613068;5861.531077513874;6006.129111278928;6037.15479832815;6097.7865774697675;7987.840243194987;9660.37207581505;9668.033196680275;9679.274054446085;9693.321467497208;9529.52352382392;9961.203103751828;9977.45124018186;10047.799044018933;10191.592336306465;6785.08401365829;7239.529430586995;7459.142198647008;7465.888011679937;7656.732014379692;8152.959235203786;8168.918310816815;8221.301189398968;8255.834883302181;8259.95870020646;7260.237675044255;7427.517211381243;7430.554166750012;7527.247275272429;7544.849103017799;7223.927760722326;7292.869605211201;7321.963390183015;7381.557106573623;7390.260973902567;6914;6990.965045174741;7003.3346832050875;7004.824879378057;7010.894836577353;4368.733067410415;4387.254166791717;4751.287306903854;4810.7113573185725;4820;4480.551944805494;4829.589484893683;4867.099586576494;4952.331435256195;4956.900861982668 17:38:42 INFO - PROCESS | 1671 | |4;prototype.html;286.61736581663484;318.2759222768202;318.49359518365793;325.54586352038905;333.98497067631956;6512.641804700739;6595.934040659592;6618.635975021378;6630.834229144268;6698.229703584089;6680.665966701657;6698.8995165072565;6699.99500074989;6704.2625311215725;6737.831554211147;4887.951120488803;4902.97548512257;4921.138800709861;4923.040007198598;4925.793180670732;4920.532549407801;4932.383452068491;4935.925961110586;4938.80244790208;4942.406911170665;4881.8535443936735;4889.046635906;4895.265710143489;4896.853094407174;4910.337104490902;4627.167109920213;4798.256270278112;4880.414350277953;4898.093852637256;4905.386826646669;4079.2249472600406;4087.816048277835;4089.284375234322;4100.610442008035;4123.670106391482;6310.211223597051;6332.778352757678;6353.110564520971;6363.268224154235;6372.299047104815;5692.57305702074;5737.598368114233;5742.454466825645;5753.856153596152;5762.510186634141;7181.102362204724;7193.172785129725;7222.422206223542;7235.131784185879;7257.963710181468;4445.933311000337;4614.423197100363;4626.236670949289;4632.0967411354795;4633.582977532038;6239.845628558685;7500.662470188855;7540.208278130772;7558.244175582453;7567.243275672388;4688.507706690816;4695.600873925755;4706.95293047072;4715.575598196178;4717.22165842639;3925.3130702127582;4759.095771803415;4762.452317983407;4767.6662633615315;4768.520369407888;5700.40145784695;5706.087026075808;5714.085746280575;5717.628354156966;5726.599138060295;5868.38381969887;6941.132358455193;7204.027456293334;7210.639468026578;7272.236415176436;1630.4293497275858;1645.12808211648;1660.4852495726363;1663.8835281530178;1664.9084300363354;982.1013772398176;1038.8182068138196;1043.5721354244724;1061.0768631290825;1067.0236733388863;6556.934430655632;6708.563943343667;6721.395074443323;6729.091572637729;6735.090762747064;7526.473146879666;7530.548167109991;7536.24637536253;7541.5475071495885;7555.546667199876;6839.863202736017;6860.245373008965;6868.038474613558;6871.622060786604;6880.415164710956;3824.4263360495593;3925.646691797752;3951.7826519541127;3963.227170701743;3966.6033396660105;6468.353164683588;7842.686292548234;7856.84286314282;7882.921170788333;7904.367650587939;7751.108622508484;7760.534367937829;7764.145943946161;7777.183395743478;7791.376689864797;7867.881981770278;7877.369810415267;7895.6052197391045;7898.526088434713;7915;7269.709211631475;7494.288042635941;7500.099988001475;7528.473006889465;7597.088349398107;6348.714307856065;7083.893741593777;7452.590107543921;7455.925440745523;7484.064491938508;7903.881441778493;7904.486208396436;7916.485428447132;7929.167437419219;7935.563544005136;4614;4671.766411679335;4679.625629949528;4685.39089918322;4702.52974702527;7221.386182174574;7472.738454154079;7487.663055162422;7491.288327608869;7511.9624401877645;7196.460265480006;7224.241454647186;7256.7822965311125;7259.310365515267;7291.197968223385;7198.6400679964745;7224;7226.313500217471;7237.601931893586;7240.841465365516;6969.059177011241;7024.2975702431395;7037.064807178067;7050.294970502909;7055.611941343275;6958.199807022257;7212.783616491513;7221.494495385273;7234.385077268491;7235.372041290878;6338.968305158444;6490.902636460362;6936.410405115622;7059.517501324779;7133.215346312006 17:38:42 INFO - PROCESS | 1671 | |5;yui.html;662.7614058938784;684.5789839248862;749.093596747935;752.4995877741302;760.2853317881192;11734.237274577146;14968.401263949429;15267.541973740777;15289.311980960856;15293.617659558517;13341.13282636628;13400.396982135802;13403.46386144553;13426.99297552685;13432.66418339542;717.0892965933261;884.3809333466567;888.1473785166248;919.3932004876783;919.8988111307751;900.5767289373983;903.8011637439786;916.7983043730369;917.4311926605513;917.6512925088457;940.9623615055424;973.1582181413062;976.3751199232497;976.4336684722879;977.3451787302489;949.8765160529122;959.0457494792726;966.4201479112546;968.9176420004308;972.3485264872517;616.3435940723116;714.5248409807472;722.7073035420663;722.7217521254303;727.2291371146601;14505.202213878232;14516.564503064925;14535.200563968978;14560.199189044704;14604.561863144123;11008.559657613685;12530.498780048787;12714.237145771283;12876.034297427637;13023.36180078066;8530.274926631244;10011.349262298;10344.948275258575;10533.841992370044;10616.044555989998;3432.3993301172445;3446.638102999199;3508.7543871928974;3558.626344233844;3737.495438115847;82329;82739.5863020681;82850;84156.57921710353;85222.57388713078;9336.346455748033;9336.439813611205;9363.485008324607;9433.151016408556;9470.95264523673;20540.897295513725;20717;20790.896045519676;20817;20860.374188774058;5798.362180160179;6473.579217350857;6513;7384.667689953965;7419.109706835215;4018.2566225248424;4145.813438395279;4146.150039241948;4157.729747566459;4181.142865712522;3371.8145501997137;3378.527006219082;3409.880654177092;3423.9657603424143;3435.381631306339;535.2559941681067;538.6902531044586;545.8608054945963;608.5223099866583;617.7806878558137;513.5506431872112;547.7069767674354;554.1272495819101;565.21435205065;604.4650484321319;8030.357571394275;8176.877346839802;8239.711610093618;8271.1728827118;8510.276626486819;576.2537513919401;589.9262592175978;592.4519819167339;593.2821286243693;619.3899009475663;3760.2291530236234;3806.923861522754;3812.370958791824;3827.25368553135;3849.8075096245634;91393.08606913846;93249.06750932404;96317.03682963221;96775;96949;69230.61538769197;69285.22151234202;72015;73349.02478149421;73458.63270683613;64774.676126619066;66480.33519664838;68161.65919170472;69467;70474;7631.38948884099;7664.080310362792;7688.538687678533;7716.5370077795515;7798.103218129988;50308.74845625748;50597.494025060754;50616;50816.23775643294;51349.48650513597;51433;64190.037149441865;68518.65740671265;68767.65616171887;69539.65230173817;9776.951115244377;9848.950755246464;9995.450250236307;10087.596496140071;10102.444365559964;10571.101456376295;10629.574817007233;10794.73013174677;10813.837792433276;11216.046636035702;10134.138598218939;10180.643677471253;10363.378197308186;10379;10710.625128120479;10028.69913902584;10169.898301016896;10435.94782026085;10774.892251077388;10780.407077610807;10207.795844082928;11085.944570277097;11250.66248012561;11315.547378105113;11787.587434439753;9370.812583748151;9494.851978840568;9519.619215231312;9575.329726919048;9670.226381889574;8321.791955201168;8815.633576795606;9441.952790236004;9861.65484208078;9938.552765125442 17:38:42 INFO - PROCESS | 1671 | __end_tp_report 17:38:42 INFO - PROCESS | 1671 | __start_cc_report 17:38:42 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,415 17:38:42 INFO - PROCESS | 1671 | __end_cc_report 17:38:42 INFO - PROCESS | 1671 | __startTimestamp1464395922418__endTimestamp 17:38:42 INFO - PROCESS | 1671 | 17:38:42 INFO - PROCESS | 1671 | ------- Summary: start ------- 17:38:42 INFO - PROCESS | 1671 | Number of tests: 6 17:38:42 INFO - PROCESS | 1671 | 17:38:42 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5740.08 Median:5995.19 stddev:692.58 (11.6%) stddev-sans-first:656.26 17:38:42 INFO - PROCESS | 1671 | Values: 2839.4 2976.8 3502.6 3534.0 3565.8 5815.7 5823.1 5840.0 5860.1 5886.4 5806.4 5825.8 5884.6 5916.6 5967.3 4438.5 5247.0 5304.7 5352.9 5401.4 5134.9 5293.4 5295.5 5301.5 5316.9 4314.6 5312.3 5314.9 5319.6 5329.0 5225.6 5301.7 5309.2 5345.4 5385.1 4396.9 4407.0 5251.4 5329.4 5393.8 5323.7 5557.9 5711.5 5859.6 5894.6 6165.2 6214.0 6224.5 6242.8 6278.2 6267.3 6268.1 6273.8 6297.1 6306.7 4835.7 5857.2 5974.8 6017.4 6053.1 6155.8 6197.3 6207.2 6215.1 6227.3 5903.6 5919.8 5932.0 5962.1 5995.2 6077.4 6137.4 6221.3 6225.6 6279.3 5449.0 6277.1 6311.1 6327.6 6348.2 6075.5 6185.3 6320.9 6322.0 6326.4 4914.4 4942.6 4956.9 5012.2 5052.8 4478.1 4482.1 4489.6 4495.3 4531.4 5655.1 5864.9 5884.9 5906.8 5915.2 5363.3 6203.7 6214.7 6278.7 6302.1 4795.6 5677.1 5851.6 5872.5 5893.7 4572.5 4645.6 5513.1 5520.1 5542.0 5461.9 6080.2 6100.8 6381.6 6418.7 6256.4 6258.0 6291.3 6295.0 6317.2 6104.3 6466.9 6474.4 6477.8 6578.8 6094.5 6150.7 6243.9 6263.4 6291.2 6247.7 6276.7 6277.7 6287.1 6297.3 6058.0 6058.1 6234.6 6287.4 6291.6 5306.7 6202.8 6250.4 6259.1 6263.9 5966.3 5998.4 6015.4 6053.4 6087.5 6023.5 6112.4 6192.1 6233.1 6247.5 6054.0 6081.8 6096.7 6106.3 6120.8 17:38:42 INFO - PROCESS | 1671 | 17:38:42 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:68705.65 Median:39813.30 stddev:146171.67 (367.1%) stddev-sans-first:146492.00 17:38:42 INFO - PROCESS | 1671 | Values: 496.8 512.3 541.6 541.8 563.4 38893.8 39809.8 39856.0 39858.6 40029.4 31979.4 41657.0 41681.2 41699.8 41980.0 8330.6 8384.5 9526.4 9748.0 9755.5 9530.1 9696.0 9769.1 9776.8 9791.7 9692.9 9747.5 9782.4 9794.9 9805.4 9731.1 9795.8 9797.2 9799.8 9818.7 8152.3 8230.5 9492.5 9601.2 9722.8 33093.5 38531.0 39669.4 39816.8 40188.8 3171.3 3482.6 3491.8 3776.1 3805.5 29076.7 29144.7 29184.4 29314.0 29411.0 2757.2 2759.8 2777.6 2782.6 2817.4 456056.0 458221.0 461035.0 461638.0 462748.0 65031.0 65361.3 65637.0 65669.0 65673.3 69319.3 69711.3 70995.0 73108.6 73249.3 17294.0 17814.8 17842.4 18005.5 18085.9 13823.8 13853.2 13876.4 13952.7 13992.0 16765.2 19830.0 20137.9 20145.5 20149.4 8002.8 8184.9 8232.5 8240.9 8291.4 974.6 1022.8 1028.6 1057.5 1074.3 30562.4 30926.4 31158.8 31209.2 31380.4 1120.3 1121.8 1141.3 1191.2 1247.5 14659.2 15803.0 15825.5 15992.3 16640.0 615704.0 616339.0 618564.0 623187.0 625889.0 60970.1 62442.0 62582.7 63331.0 63471.0 1494.0 1515.3 1515.3 1517.7 1521.7 29906.6 30322.7 30818.7 30871.7 31534.4 39238.8 41617.8 55370.0 55658.0 55659.0 541893.0 551773.0 554097.5 555011.0 557586.0 27810.9 28211.7 36127.8 43718.3 44916.6 43557.3 44081.0 44110.1 44999.6 45056.8 32182.5 32243.5 38326.2 40014.8 42749.0 31452.8 35705.6 36528.3 36844.4 36853.6 36910.0 38674.4 38777.4 39601.8 40343.8 32425.0 33301.8 33911.7 34075.0 34184.3 24684.8 25073.7 25272.6 25431.9 25917.4 17:38:42 INFO - PROCESS | 1671 | 17:38:42 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:33599.17 Median:391.48 stddev:99238.18 (25349.2%) stddev-sans-first:99485.23 17:38:42 INFO - PROCESS | 1671 | Values: 382.0 401.0 401.4 401.9 406.4 14702.2 16280.4 20007.2 20743.8 20813.0 16472.0 19708.6 20515.8 20516.7 20567.9 5296.7 5453.7 5467.3 5473.3 5478.4 5118.4 5375.8 5379.7 5380.5 5387.8 5241.7 5327.0 5340.8 5369.9 5388.7 4628.2 5332.1 5798.7 5937.0 5950.0 5682.3 5736.6 5768.7 5787.5 5788.3 13274.7 13620.0 13657.5 13663.8 22228.6 2145.9 2209.4 2216.1 2289.6 2301.3 9508.7 9721.0 9822.4 10120.5 10325.3 1434.7 1728.3 1728.4 1749.6 1774.3 96871.5 106026.0 109270.9 113162.9 116466.0 11817.4 12003.5 12045.6 12074.5 12113.8 15263.0 15311.9 15328.0 15331.1 15365.0 8909.9 8953.6 8998.9 9060.4 9118.0 8989.9 9009.5 9051.3 9115.5 9143.0 4064.4 4633.9 4724.5 4734.2 4778.5 1994.5 2001.2 2019.6 2026.6 2070.3 797.7 825.4 897.3 1004.2 1022.2 19719.3 20311.6 20458.6 20602.0 20693.3 959.6 984.5 1006.9 1007.2 1026.2 10714.4 11090.4 11099.9 11121.6 11139.7 582931.0 593000.0 600016.0 605486.0 607122.0 112182.0 113505.0 114025.0 115414.0 115848.4 826.7 971.7 975.4 983.1 1030.8 14736.5 17774.0 17865.9 18012.4 18096.9 47609.8 48803.3 49854.0 49973.5 50358.2 70516.6 72254.0 72518.3 73607.5 73792.9 8220.5 8246.3 8281.3 8332.4 8519.7 12241.6 12514.8 12697.4 12871.2 12902.5 8256.5 8423.4 10618.9 10784.5 11260.6 9253.8 9266.8 10622.3 10774.8 10786.3 5609.5 7947.9 8474.1 10282.8 10759.3 8225.8 8236.3 8321.0 8462.2 8630.7 10550.6 10642.5 11067.5 11303.2 11309.9 17:38:42 INFO - PROCESS | 1671 | 17:38:42 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5684.61 Median:4864.49 stddev:3139.00 (64.5%) stddev-sans-first:3131.03 17:38:42 INFO - PROCESS | 1671 | Values: 1365.7 1596.9 1781.9 1899.5 1944.3 7587.5 7996.7 8567.7 8716.3 8901.8 8630.8 8665.8 8734.6 8750.0 8759.0 2422.5 2430.7 2439.3 2440.5 2442.7 2418.5 2422.6 2460.5 2466.5 2481.0 2424.7 2440.3 2455.1 2461.9 2468.7 2433.4 2448.4 2454.4 2463.5 2463.9 2449.0 2450.7 2466.2 2468.8 2479.4 8918.5 8946.6 8951.1 8975.7 8977.6 5348.7 5474.0 5491.2 5520.3 5538.9 2957.3 3454.1 3545.0 3606.7 3625.2 1010.7 1020.5 1030.8 1036.9 1052.1 11711.0 11726.1 11856.8 11857.1 11892.2 4706.9 4777.0 4854.9 4860.3 4861.9 10029.9 10113.0 10122.0 10164.4 10192.2 5408.4 5421.2 5445.7 5447.5 5472.2 4396.9 4850.5 4919.4 4973.0 5029.4 3379.5 4051.6 4098.2 4147.6 4175.2 1156.6 1354.4 1379.2 1385.6 1386.1 798.8 921.6 930.3 931.5 932.0 5558.4 5855.9 5936.4 5995.0 6082.0 931.4 935.4 967.7 991.0 995.3 3767.4 3769.8 3864.3 3872.1 3947.9 10250.3 10454.5 10503.4 10535.7 10570.3 10207.3 10407.3 10417.6 10433.0 10452.2 7001.2 7706.5 8372.5 8444.2 8456.9 5780.9 5861.5 6006.1 6037.2 6097.8 7987.8 9660.4 9668.0 9679.3 9693.3 9529.5 9961.2 9977.5 10047.8 10191.6 6785.1 7239.5 7459.1 7465.9 7656.7 8153.0 8168.9 8221.3 8255.8 8260.0 7260.2 7427.5 7430.6 7527.2 7544.8 7223.9 7292.9 7322.0 7381.6 7390.3 6914.0 6991.0 7003.3 7004.8 7010.9 4368.7 4387.3 4751.3 4810.7 4820.0 4480.6 4829.6 4867.1 4952.3 4956.9 17:38:42 INFO - PROCESS | 1671 | 17:38:42 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:5874.18 Median:6689.45 stddev:1906.56 (28.5%) stddev-sans-first:1865.22 17:38:42 INFO - PROCESS | 1671 | Values: 286.6 318.3 318.5 325.5 334.0 6512.6 6595.9 6618.6 6630.8 6698.2 6680.7 6698.9 6700.0 6704.3 6737.8 4888.0 4903.0 4921.1 4923.0 4925.8 4920.5 4932.4 4935.9 4938.8 4942.4 4881.9 4889.0 4895.3 4896.9 4910.3 4627.2 4798.3 4880.4 4898.1 4905.4 4079.2 4087.8 4089.3 4100.6 4123.7 6310.2 6332.8 6353.1 6363.3 6372.3 5692.6 5737.6 5742.5 5753.9 5762.5 7181.1 7193.2 7222.4 7235.1 7258.0 4445.9 4614.4 4626.2 4632.1 4633.6 6239.8 7500.7 7540.2 7558.2 7567.2 4688.5 4695.6 4707.0 4715.6 4717.2 3925.3 4759.1 4762.5 4767.7 4768.5 5700.4 5706.1 5714.1 5717.6 5726.6 5868.4 6941.1 7204.0 7210.6 7272.2 1630.4 1645.1 1660.5 1663.9 1664.9 982.1 1038.8 1043.6 1061.1 1067.0 6556.9 6708.6 6721.4 6729.1 6735.1 7526.5 7530.5 7536.2 7541.5 7555.5 6839.9 6860.2 6868.0 6871.6 6880.4 3824.4 3925.6 3951.8 3963.2 3966.6 6468.4 7842.7 7856.8 7882.9 7904.4 7751.1 7760.5 7764.1 7777.2 7791.4 7867.9 7877.4 7895.6 7898.5 7915.0 7269.7 7494.3 7500.1 7528.5 7597.1 6348.7 7083.9 7452.6 7455.9 7484.1 7903.9 7904.5 7916.5 7929.2 7935.6 4614.0 4671.8 4679.6 4685.4 4702.5 7221.4 7472.7 7487.7 7491.3 7512.0 7196.5 7224.2 7256.8 7259.3 7291.2 7198.6 7224.0 7226.3 7237.6 7240.8 6969.1 7024.3 7037.1 7050.3 7055.6 6958.2 7212.8 7221.5 7234.4 7235.4 6339.0 6490.9 6936.4 7059.5 7133.2 17:38:42 INFO - PROCESS | 1671 | 17:38:42 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17885.49 Median:612.43 stddev:25519.96 (4167.0%) stddev-sans-first:25558.78 17:38:42 INFO - PROCESS | 1671 | Values: 662.8 684.6 749.1 752.5 760.3 11734.2 14968.4 15267.5 15289.3 15293.6 13341.1 13400.4 13403.5 13427.0 13432.7 717.1 884.4 888.1 919.4 919.9 900.6 903.8 916.8 917.4 917.7 941.0 973.2 976.4 976.4 977.3 949.9 959.0 966.4 968.9 972.3 616.3 714.5 722.7 722.7 727.2 14505.2 14516.6 14535.2 14560.2 14604.6 11008.6 12530.5 12714.2 12876.0 13023.4 8530.3 10011.3 10344.9 10533.8 10616.0 3432.4 3446.6 3508.8 3558.6 3737.5 82329.0 82739.6 82850.0 84156.6 85222.6 9336.3 9336.4 9363.5 9433.2 9471.0 20540.9 20717.0 20790.9 20817.0 20860.4 5798.4 6473.6 6513.0 7384.7 7419.1 4018.3 4145.8 4146.2 4157.7 4181.1 3371.8 3378.5 3409.9 3424.0 3435.4 535.3 538.7 545.9 608.5 617.8 513.6 547.7 554.1 565.2 604.5 8030.4 8176.9 8239.7 8271.2 8510.3 576.3 589.9 592.5 593.3 619.4 3760.2 3806.9 3812.4 3827.3 3849.8 91393.1 93249.1 96317.0 96775.0 96949.0 69230.6 69285.2 72015.0 73349.0 73458.6 64774.7 66480.3 68161.7 69467.0 70474.0 7631.4 7664.1 7688.5 7716.5 7798.1 50308.7 50597.5 50616.0 50816.2 51349.5 51433.0 64190.0 68518.7 68767.7 69539.7 9777.0 9849.0 9995.5 10087.6 10102.4 10571.1 10629.6 10794.7 10813.8 11216.0 10134.1 10180.6 10363.4 10379.0 10710.6 10028.7 10169.9 10435.9 10774.9 10780.4 10207.8 11085.9 11250.7 11315.5 11787.6 9370.8 9494.9 9519.6 9575.3 9670.2 8321.8 8815.6 9442.0 9861.7 9938.6 17:38:42 INFO - PROCESS | 1671 | -------- Summary: end -------- 17:38:42 INFO - PROCESS | 1671 | 17:38:43 INFO - TEST-INFO | 1671: exit 0 17:38:43 INFO - TEST-OK | dromaeo_css | took 1111642ms 17:38:43 INFO - TEST-START | kraken 17:38:43 INFO - operating with platform_type : mac_ 17:38:43 INFO - Initialising browser for kraken test... 17:38:43 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpNh17VG/profile http://localhost:49270/getInfo.html) 17:38:45 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 17:38:45 INFO - PROCESS | 1680 | colorDepth:24 17:38:45 INFO - PROCESS | 1680 | Browser inner width/height: 1024/689 17:38:45 INFO - PROCESS | 1680 | __metrics 17:38:45 ERROR - PROCESS | 1680 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 17:38:46 INFO - TEST-INFO | 1680: exit 0 17:38:46 INFO - Browser initialized. 17:38:46 INFO - Running cycle 1/1 for kraken test... 17:38:46 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpNh17VG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:38:58 INFO - PROCESS | 1683 | RSS: Main: 221057024 17:38:58 INFO - PROCESS | 1683 | 17:39:01 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 17:39:02 INFO - PROCESS | 1683 | RSS: Main: 233377792 17:39:02 INFO - PROCESS | 1683 | 17:39:04 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 17:39:05 INFO - PROCESS | 1683 | RSS: Main: 242589696 17:39:05 INFO - PROCESS | 1683 | 17:39:08 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 17:39:09 INFO - PROCESS | 1683 | RSS: Main: 245784576 17:39:09 INFO - PROCESS | 1683 | 17:39:11 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 17:39:11 INFO - PROCESS | 1683 | RSS: Main: 245972992 17:39:11 INFO - PROCESS | 1683 | 17:39:13 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:39:14 INFO - PROCESS | 1683 | RSS: Main: 246165504 17:39:14 INFO - PROCESS | 1683 | 17:39:17 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 17:39:18 INFO - PROCESS | 1683 | RSS: Main: 247132160 17:39:18 INFO - PROCESS | 1683 | 17:39:21 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 17:39:21 INFO - PROCESS | 1683 | RSS: Main: 248299520 17:39:21 INFO - PROCESS | 1683 | 17:39:24 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 17:39:25 INFO - PROCESS | 1683 | RSS: Main: 248475648 17:39:25 INFO - PROCESS | 1683 | 17:39:26 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:39:27 INFO - PROCESS | 1683 | RSS: Main: 248360960 17:39:27 INFO - PROCESS | 1683 | 17:39:29 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:39:30 INFO - PROCESS | 1683 | RSS: Main: 247705600 17:39:30 INFO - PROCESS | 1683 | 17:39:32 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:39:32 INFO - PROCESS | 1683 | RSS: Main: 247787520 17:39:32 INFO - PROCESS | 1683 | 17:39:35 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:39:36 INFO - PROCESS | 1683 | RSS: Main: 247730176 17:39:36 INFO - PROCESS | 1683 | 17:39:39 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:39:39 INFO - PROCESS | 1683 | RSS: Main: 247746560 17:39:39 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 17:39:41 INFO - PROCESS | 1683 | RSS: Main: 249442304 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | __start_tp_report 17:39:41 INFO - PROCESS | 1683 | _x_x_mozilla_page_load 17:39:41 INFO - PROCESS | 1683 | _x_x_mozilla_page_load_details 17:39:41 INFO - PROCESS | 1683 | |i|pagename|runs| 17:39:41 INFO - PROCESS | 1683 | |0;ai-astar;113;118;109;119;115;119;106;118;116;116 17:39:41 INFO - PROCESS | 1683 | |1;audio-beat-detection;152;127;161;155;124;151;144;123;169;120 17:39:41 INFO - PROCESS | 1683 | |2;audio-dft;213;278;223;200;201;200;237;195;197;193 17:39:41 INFO - PROCESS | 1683 | |3;audio-fft;71;111;107;171;59;56;58;64;123;57 17:39:41 INFO - PROCESS | 1683 | |4;audio-oscillator;76;82;132;77;80;78;118;79;80;117 17:39:41 INFO - PROCESS | 1683 | |5;imaging-gaussian-blur;88;85;88;97;138;86;88;85;108;131 17:39:41 INFO - PROCESS | 1683 | |6;imaging-darkroom;95;150;103;151;130;103;102;93;94;96 17:39:41 INFO - PROCESS | 1683 | |7;imaging-desaturate;137;148;90;118;83;89;91;90;91;91 17:39:41 INFO - PROCESS | 1683 | |8;json-parse-financial;54;64;66;66;66;58;54;110;99;94 17:39:41 INFO - PROCESS | 1683 | |9;json-stringify-tinderbox;67;66;69;70;73;73;75;70;70;71 17:39:41 INFO - PROCESS | 1683 | |10;stanford-crypto-aes;80;83;81;83;83;84;82;82;83;88 17:39:41 INFO - PROCESS | 1683 | |11;stanford-crypto-ccm;132;127;133;131;132;130;132;143;156;170 17:39:41 INFO - PROCESS | 1683 | |12;stanford-crypto-pbkdf2;212;185;177;169;170;169;168;168;206;168 17:39:41 INFO - PROCESS | 1683 | |13;stanford-crypto-sha256-iterative;59;73;69;63;63;83;81;101;99;101 17:39:41 INFO - PROCESS | 1683 | __end_tp_report 17:39:41 INFO - PROCESS | 1683 | __start_cc_report 17:39:41 INFO - PROCESS | 1683 | _x_x_mozilla_cycle_collect,1088 17:39:41 INFO - PROCESS | 1683 | __end_cc_report 17:39:41 INFO - PROCESS | 1683 | __startTimestamp1464395981813__endTimestamp 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | ------- Summary: start ------- 17:39:41 INFO - PROCESS | 1683 | Number of tests: 14 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | [#0] ai-astar Cycles:10 Average:114.90 Median:117.00 stddev:4.38 (3.7%) stddev-sans-first:4.59 17:39:41 INFO - PROCESS | 1683 | Values: 113.0 118.0 109.0 119.0 115.0 119.0 106.0 118.0 116.0 116.0 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | [#1] audio-beat-detection Cycles:10 Average:142.60 Median:151.50 stddev:17.75 (11.7%) stddev-sans-first:18.49 17:39:41 INFO - PROCESS | 1683 | Values: 152.0 127.0 161.0 155.0 124.0 151.0 144.0 123.0 169.0 120.0 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | [#2] audio-dft Cycles:10 Average:213.70 Median:207.00 stddev:26.58 (12.8%) stddev-sans-first:28.19 17:39:41 INFO - PROCESS | 1683 | Values: 213.0 278.0 223.0 200.0 201.0 200.0 237.0 195.0 197.0 193.0 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | [#3] audio-fft Cycles:10 Average:87.70 Median:57.50 stddev:38.86 (67.6%) stddev-sans-first:40.75 17:39:41 INFO - PROCESS | 1683 | Values: 71.0 111.0 107.0 171.0 59.0 56.0 58.0 64.0 123.0 57.0 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:78.50 stddev:21.43 (27.3%) stddev-sans-first:21.95 17:39:41 INFO - PROCESS | 1683 | Values: 76.0 82.0 132.0 77.0 80.0 78.0 118.0 79.0 80.0 117.0 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | [#5] imaging-gaussian-blur Cycles:10 Average:99.40 Median:87.00 stddev:19.87 (22.8%) stddev-sans-first:20.64 17:39:41 INFO - PROCESS | 1683 | Values: 88.0 85.0 88.0 97.0 138.0 86.0 88.0 85.0 108.0 131.0 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | [#6] imaging-darkroom Cycles:10 Average:111.70 Median:122.00 stddev:23.05 (18.9%) stddev-sans-first:23.64 17:39:41 INFO - PROCESS | 1683 | Values: 95.0 150.0 103.0 151.0 130.0 103.0 102.0 93.0 94.0 96.0 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | [#7] imaging-desaturate Cycles:10 Average:102.80 Median:90.00 stddev:23.03 (25.6%) stddev-sans-first:20.83 17:39:41 INFO - PROCESS | 1683 | Values: 137.0 148.0 90.0 118.0 83.0 89.0 91.0 90.0 91.0 91.0 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | [#8] json-parse-financial Cycles:10 Average:73.10 Median:66.00 stddev:20.17 (30.6%) stddev-sans-first:20.17 17:39:41 INFO - PROCESS | 1683 | Values: 54.0 64.0 66.0 66.0 66.0 58.0 54.0 110.0 99.0 94.0 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:70.50 stddev:2.76 (3.9%) stddev-sans-first:2.64 17:39:41 INFO - PROCESS | 1683 | Values: 67.0 66.0 69.0 70.0 73.0 73.0 75.0 70.0 70.0 71.0 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:83.00 stddev:2.13 (2.6%) stddev-sans-first:1.99 17:39:41 INFO - PROCESS | 1683 | Values: 80.0 83.0 81.0 83.0 83.0 84.0 82.0 82.0 83.0 88.0 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | [#11] stanford-crypto-ccm Cycles:10 Average:138.60 Median:132.50 stddev:13.89 (10.5%) stddev-sans-first:14.53 17:39:41 INFO - PROCESS | 1683 | Values: 132.0 127.0 133.0 131.0 132.0 130.0 132.0 143.0 156.0 170.0 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.20 Median:173.50 stddev:16.67 (9.6%) stddev-sans-first:12.78 17:39:41 INFO - PROCESS | 1683 | Values: 212.0 185.0 177.0 169.0 170.0 169.0 168.0 168.0 206.0 168.0 17:39:41 INFO - PROCESS | 1683 | 17:39:41 INFO - PROCESS | 1683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.20 Median:71.00 stddev:16.45 (23.2%) stddev-sans-first:15.74 17:39:41 INFO - PROCESS | 1683 | Values: 59.0 73.0 69.0 63.0 63.0 83.0 81.0 101.0 99.0 101.0 17:39:41 INFO - PROCESS | 1683 | -------- Summary: end -------- 17:39:41 INFO - PROCESS | 1683 | 17:39:42 INFO - TEST-INFO | 1683: exit 0 17:39:42 INFO - TEST-OK | kraken | took 59522ms 17:39:42 INFO - SUITE-END | took 1171s 17:39:42 INFO - Completed test suite (00:19:31) 17:39:42 INFO - javascript benchmark 17:39:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2839.389531250781, 2976.8213907165587, 3502.63222361652, 3533.982330088349, 3565.750397472177, 5815.709214539267, 5823.126531020349, 5840.007198776202, 5860.120981852713, 5886.440788125121, 5806.361300256968, 5825.8252252432385, 5884.588078834483, 5916.585838991272, 5967.254093238345, 4438.4895736990175, 5246.973765131179, 5304.681719096847, 5352.866178345534, 5401.4328495508, 5134.897302053957, 5293.364796224459, 5295.4704529547125, 5301.549368303698, 5316.946830531703, 4314.568543145691, 5312.256284120207, 5314.92027619586, 5319.574434045287, 5329.0, 5225.581953443734, 5301.7084060376665, 5309.150535914235, 5345.385280692732, 5385.138377859563, 4396.868093957154, 4406.955930440687, 5251.422343542207, 5329.413764485904, 5393.7572809223675, 5323.733813309319, 5557.888842223133, 5711.543076553866, 5859.560532960045, 5894.616849904743, 6165.229346331708, 6214.0, 6224.502039836848, 6242.812715618494, 6278.183836101278, 6267.279262884781, 6268.122462855204, 6273.843153921142, 6297.149884765589, 6306.747730090791, 4835.734034628129, 5857.15071314657, 5974.761009559613, 6017.42834430724, 6053.092036194562, 6155.815325540151, 6197.25632924052, 6207.193064901534, 6215.129881816459, 6227.283862355796, 5903.616264942765, 5919.763209471659, 5932.0, 5962.135490353874, 5995.190649262294, 6077.36187700294, 6137.386261373917, 6221.2845522764555, 6225.595336303217, 6279.340669229665, 5448.972755136199, 6277.058441233851, 6311.084892690534, 6327.588706734047, 6348.206474190726, 6075.544334174954, 6185.310756932475, 6320.873582528324, 6322.0, 6326.43062124411, 4914.385701787277, 4942.604591632661, 4956.900861982812, 5012.172991456442, 5052.848414547496, 4478.149151661174, 4482.148391805546, 4489.6408287336935, 4495.303227999665, 4531.410916580823, 5655.095184770499, 5864.941350586525, 5884.941150588439, 5906.822795316148, 5915.231019967377, 5363.302770639792, 6203.689815509207, 6214.658193799294, 6278.717457714414, 6302.054691796267, 4795.6163506919365, 5677.120046392815, 5851.64890106595, 5872.500837428866, 5893.705314734332, 4572.45130584332, 4645.581897629229, 5513.145462453326, 5520.1167813149705, 5542.002439560918, 5461.863453413696, 6080.209572755513, 6100.847478813065, 6381.553291269659, 6418.679066046679, 6256.405641464054, 6257.9687101564205, 6291.307956124914, 6294.968525157528, 6317.241930968313, 6104.331286938502, 6466.870662586628, 6474.449671777952, 6477.773277935438, 6578.809277500312, 6094.481969032682, 6150.692465376624, 6243.907316219553, 6263.404976527405, 6291.182146320949, 6247.656378899204, 6276.686165691788, 6277.686115694289, 6287.119803227643, 6297.275813281532, 6057.969710151421, 6058.091286307089, 6234.594751341148, 6287.371262873676, 6291.591046581958, 5306.734663266744, 6202.844928876814, 6250.3749625037135, 6259.092431597484, 6263.874722505616, 5966.284045914518, 5998.400159984141, 6015.39846015395, 6053.364396738279, 6087.4521293083835, 6023.45788879003, 6112.41229444207, 6192.102145188832, 6233.127362169209, 6247.531435142291, 6053.969730151321, 6081.756729730761, 6096.725647345791, 6106.267247930276, 6120.782376608402], "value": 5695.979065365672, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.79134763399344, 512.2955935588566, 541.5748637319705, 541.8080223508283, 563.4253061876885, 38893.80553097234, 39809.805705828796, 39856.003599910015, 39858.604948826796, 40029.39955900664, 31979.36041279173, 41657.0, 41681.166376672445, 41699.79150104253, 41979.95050123749, 8330.62512186953, 8384.538850363213, 9526.380785248937, 9748.0, 9755.512224388787, 9530.14228719415, 9695.951520242388, 9769.120779129877, 9776.755581110458, 9791.706248812512, 9692.90307096927, 9747.463889486075, 9782.413055216673, 9794.902050979506, 9805.36265142767, 9731.124198822105, 9795.804083918352, 9797.216222702167, 9799.755006124868, 9818.65634702789, 8152.2662960333855, 8230.547319897403, 9492.525373731354, 9601.231901447867, 9722.756931076708, 33093.503597446055, 38531.036724081845, 39669.40495892564, 39816.800915995525, 40188.79905600483, 3171.302313491028, 3482.564679415073, 3491.825408729553, 3776.0937375029794, 3805.5052843130493, 29076.70923290761, 29144.70855291441, 29184.41631167365, 29313.974010909515, 29411.0, 2757.1866299441713, 2759.765419939308, 2777.6389069420848, 2782.5826126081047, 2817.422428402193, 456056.0, 458221.0, 461035.0, 461638.0, 462748.0, 65031.0, 65361.346386536476, 65637.0, 65669.0, 65673.34326656672, 69319.30680693129, 69711.30288697149, 70995.0, 73108.63445682738, 73249.26750732424, 17294.048827314367, 17814.821851781577, 17842.375516856846, 18005.549861253574, 18085.914845109335, 13823.79264311036, 13853.16880987144, 13876.375563099684, 13952.651183720489, 13992.0, 16765.24556394965, 19830.0, 20137.899310503355, 20145.496362591053, 20149.39551813448, 8002.759917202493, 8184.877226841602, 8232.50604963704, 8240.876386854203, 8291.378146639026, 974.6296407365297, 1022.7903279827617, 1028.5525796278644, 1057.4765491081964, 1074.258761454594, 30562.38875222483, 30926.381472370427, 31158.844205778827, 31209.219769505944, 31380.37239255202, 1120.2550304047718, 1121.8036843552345, 1141.2866958151155, 1191.2197510630551, 1247.5384107880143, 14659.193744344164, 15803.0, 15825.522171394816, 15992.280347384163, 16640.0, 615704.0, 616339.0, 618564.0, 623187.0, 625889.0, 60970.0854487183, 62442.0, 62582.748345032844, 63331.0, 63471.0, 1493.9775903361458, 1515.2878147270544, 1515.3256800723793, 1517.7343964806114, 1521.6956608678308, 29906.551401729426, 30322.69677303199, 30818.691813082478, 30871.691283086882, 31534.369312613162, 39238.80380598079, 41617.75146745602, 55370.0, 55658.0, 55659.0, 541893.0, 551773.0, 554097.4590254045, 555011.0, 557586.0, 27810.860945695953, 28211.71788282091, 36127.819360904075, 43718.344224836015, 44916.55083449124, 43557.3466397998, 44081.0, 44110.11779764451, 44999.550004499535, 45056.77471612621, 32182.517262240617, 32243.549040292775, 38326.23347532978, 40014.79992600018, 42749.0, 31452.842735786173, 35705.64294357127, 36528.26943461169, 36844.44733329056, 36853.63146368502, 36910.0, 38674.41988370233, 38777.4183387255, 39601.801990991014, 40343.79828100958, 32425.0, 33301.83349083239, 33911.660883391836, 34075.0, 34184.316313674084, 24684.753152468962, 25073.749262507143, 25272.62091068672, 25431.87284063568, 25917.352066198495], "value": 20319.002529683956, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.9809009549523, 400.9879703608891, 401.39389521822056, 401.8834537983985, 406.38432774346853, 14702.233866245893, 16280.430184943529, 20007.19971201152, 20743.792562074374, 20813.0, 16472.011679299252, 19708.605827883435, 20515.794842051575, 20516.692249616266, 20567.897160514214, 5296.735163241842, 5453.727313634322, 5467.261919640838, 5473.28847249859, 5478.397376288605, 5118.436971933084, 5375.81184658537, 5379.704116273603, 5380.515753582177, 5387.757550910199, 5241.711705856176, 5326.973365133169, 5340.786368545254, 5369.919451208235, 5388.703621300827, 4628.236341003731, 5332.093544097494, 5798.7100644968, 5937.0, 5950.047992321208, 5682.346530149044, 5736.627119237236, 5768.682722450264, 5787.50806181475, 5788.305403351625, 13274.66813329665, 13619.978501612319, 13657.453701851913, 13663.795043074262, 22228.555428891494, 2145.903434345458, 2209.4476380904775, 2216.0581752755015, 2289.6336586146135, 2301.309607117858, 9508.667196648154, 9720.951395242979, 9822.361546499526, 10120.544575494121, 10325.277230593862, 1434.7274017936663, 1728.265487167961, 1728.3777839977597, 1749.615084681368, 1774.299151835031, 96871.51564242205, 106026.0, 109270.90729092687, 113162.86837131606, 116466.0, 11817.40465037209, 12003.519859205533, 12045.578404755792, 12074.456649450796, 12113.818292725617, 15263.007904486172, 15311.923440382727, 15328.003679760779, 15331.080135191702, 15365.001274917318, 8909.910900890907, 8953.59708813105, 8998.910010899937, 9060.411073280347, 9118.042605526456, 8989.910100899038, 9009.459432434074, 9051.27589792815, 9115.544222788834, 9143.0, 4064.4106604542176, 4633.907321853544, 4724.456687480915, 4734.218853889139, 4778.474367819537, 1994.4914046917943, 2001.2295266322571, 2019.6061767955403, 2026.625074361248, 2070.3478404302596, 797.6849144587856, 825.4469505431375, 897.2866571076011, 1004.2267454060333, 1022.2435397805571, 19719.30982415637, 20311.593768124556, 20458.59082818365, 20602.0, 20693.27573534919, 959.64972784933, 984.542187882638, 1006.9395836249848, 1007.2093406675724, 1026.1841835740604, 10714.410707411167, 11090.445477726082, 11099.889001109885, 11121.610743623934, 11139.721506962229, 582931.0, 593000.0, 600015.9999199976, 605486.0, 607122.0, 112182.0, 113505.0, 114025.0, 115414.0, 115848.42075789736, 826.68999125328, 971.7327734872966, 975.4196253229317, 983.0857302708549, 1030.8144533983955, 14736.48422305214, 17774.0, 17865.910670446563, 18012.36956706509, 18096.914185148933, 47609.76195119002, 48803.26795098148, 49854.0, 49973.500264998336, 50358.2446263299, 70516.64741676259, 72254.0, 72518.27481725326, 73607.52784944378, 73792.89310660452, 8220.465669731448, 8246.299064579338, 8281.29608983243, 8332.416730828783, 8519.659213631634, 12241.632751017483, 12514.812277816021, 12697.36513174319, 12871.22772633645, 12902.483900643869, 8256.463329883538, 8423.410361274651, 10618.8938110621, 10784.514696838818, 11260.605878794531, 9253.768655783659, 9266.768330791785, 10622.309549879234, 10774.838377424188, 10786.298890572089, 5609.467100625434, 7947.920520794718, 8474.067852536093, 10282.794344113225, 10759.30064545802, 8225.83548329018, 8236.258736713724, 8320.958395207985, 8462.19609137131, 8630.741077767678, 10550.577976880839, 10642.467876605984, 11067.501962411872, 11303.208775385972, 11309.886901130883], "value": 9157.25463431035, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.7473367427024, 1596.8901613378703, 1781.8930864148151, 1899.4776436479967, 1944.3389247655793, 7587.4688771785995, 7996.680132794696, 8567.742967710963, 8716.259117974972, 8901.777455563606, 8630.784230394243, 8665.826683466326, 8734.563271836414, 8749.95625021874, 8758.956205218965, 2422.491276831863, 2430.720467146274, 2439.280412278373, 2440.4752978109705, 2442.65802787609, 2418.504206637635, 2422.588160012802, 2460.4709987352717, 2466.519028789387, 2480.9751902481016, 2424.7270183153896, 2440.316711320833, 2455.0793452455327, 2461.90152393904, 2468.7037555493366, 2433.3794882305074, 2448.4368595223023, 2454.435479839638, 2463.5072985402903, 2463.8521688698734, 2448.9633054520514, 2450.656908032877, 2466.2477944227003, 2468.8148388870904, 2479.442125521761, 8918.464892106429, 8946.64213431462, 8951.14964078418, 8975.730728078168, 8977.551122443852, 5348.652337598043, 5474.042042642522, 5491.2312276281355, 5520.337559492847, 5538.889222215533, 2957.349383135707, 3454.136465883529, 3545.0428384336083, 3606.6934310583633, 3625.1843335249623, 1010.7271036820091, 1020.5254556631202, 1030.8196065688473, 1036.9325993810378, 1052.1372474570856, 11711.0, 11726.120540959462, 11856.762864742657, 11857.110716696283, 11892.167548271624, 4706.882327941828, 4776.99366266169, 4854.854354369375, 4860.270959356125, 4861.878453038632, 10029.899701002896, 10113.0, 10121.987801219819, 10164.390136591828, 10192.184625229964, 5408.3509978802795, 5421.186821976735, 5445.727713614304, 5447.509724124848, 5472.179173123984, 4396.868093957187, 4850.53919877611, 4919.3604831371695, 4973.0, 5029.446760856303, 3379.5437615921537, 4051.554329023805, 4098.159877225162, 4147.647449966787, 4175.227582897173, 1156.6125348008343, 1354.3769865861814, 1379.1793882639813, 1385.5912505810609, 1386.0782579584463, 798.8002999250187, 921.5576523268735, 930.251147825993, 931.5342328835583, 931.9813603727888, 5558.44415558441, 5855.912161317584, 5936.406359364116, 5995.040793473024, 6082.026875699867, 931.3899395895563, 935.3919952031098, 967.656481948898, 990.9752256193652, 995.3231802374455, 3767.434884767307, 3769.8115094245727, 3864.2851072551666, 3872.0513474199, 3947.881563553098, 10250.282480226462, 10454.52954617044, 10503.422311772772, 10535.736606584744, 10570.312929659547, 10207.285490015776, 10407.271490995558, 10417.5832966682, 10433.0, 10452.216083793746, 7001.229864714877, 7706.537607743553, 8372.455790373728, 8444.240018398374, 8456.915430845736, 5780.855478613068, 5861.531077513874, 6006.129111278928, 6037.15479832815, 6097.7865774697675, 7987.840243194987, 9660.37207581505, 9668.033196680275, 9679.274054446085, 9693.321467497208, 9529.52352382392, 9961.203103751828, 9977.45124018186, 10047.799044018933, 10191.592336306465, 6785.08401365829, 7239.529430586995, 7459.142198647008, 7465.888011679937, 7656.732014379692, 8152.959235203786, 8168.918310816815, 8221.301189398968, 8255.834883302181, 8259.95870020646, 7260.237675044255, 7427.517211381243, 7430.554166750012, 7527.247275272429, 7544.849103017799, 7223.927760722326, 7292.869605211201, 7321.963390183015, 7381.557106573623, 7390.260973902567, 6914.0, 6990.965045174741, 7003.3346832050875, 7004.824879378057, 7010.894836577353, 4368.733067410415, 4387.254166791717, 4751.287306903854, 4810.7113573185725, 4820.0, 4480.551944805494, 4829.589484893683, 4867.099586576494, 4952.331435256195, 4956.900861982668], "value": 4603.149378765474, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.61736581663484, 318.2759222768202, 318.49359518365793, 325.54586352038905, 333.98497067631956, 6512.641804700739, 6595.934040659592, 6618.635975021378, 6630.834229144268, 6698.229703584089, 6680.665966701657, 6698.8995165072565, 6699.99500074989, 6704.2625311215725, 6737.831554211147, 4887.951120488803, 4902.97548512257, 4921.138800709861, 4923.040007198598, 4925.793180670732, 4920.532549407801, 4932.383452068491, 4935.925961110586, 4938.80244790208, 4942.406911170665, 4881.8535443936735, 4889.046635906, 4895.265710143489, 4896.853094407174, 4910.337104490902, 4627.167109920213, 4798.256270278112, 4880.414350277953, 4898.093852637256, 4905.386826646669, 4079.2249472600406, 4087.816048277835, 4089.284375234322, 4100.610442008035, 4123.670106391482, 6310.211223597051, 6332.778352757678, 6353.110564520971, 6363.268224154235, 6372.299047104815, 5692.57305702074, 5737.598368114233, 5742.454466825645, 5753.856153596152, 5762.510186634141, 7181.102362204724, 7193.172785129725, 7222.422206223542, 7235.131784185879, 7257.963710181468, 4445.933311000337, 4614.423197100363, 4626.236670949289, 4632.0967411354795, 4633.582977532038, 6239.845628558685, 7500.662470188855, 7540.208278130772, 7558.244175582453, 7567.243275672388, 4688.507706690816, 4695.600873925755, 4706.95293047072, 4715.575598196178, 4717.22165842639, 3925.3130702127582, 4759.095771803415, 4762.452317983407, 4767.6662633615315, 4768.520369407888, 5700.40145784695, 5706.087026075808, 5714.085746280575, 5717.628354156966, 5726.599138060295, 5868.38381969887, 6941.132358455193, 7204.027456293334, 7210.639468026578, 7272.236415176436, 1630.4293497275858, 1645.12808211648, 1660.4852495726363, 1663.8835281530178, 1664.9084300363354, 982.1013772398176, 1038.8182068138196, 1043.5721354244724, 1061.0768631290825, 1067.0236733388863, 6556.934430655632, 6708.563943343667, 6721.395074443323, 6729.091572637729, 6735.090762747064, 7526.473146879666, 7530.548167109991, 7536.24637536253, 7541.5475071495885, 7555.546667199876, 6839.863202736017, 6860.245373008965, 6868.038474613558, 6871.622060786604, 6880.415164710956, 3824.4263360495593, 3925.646691797752, 3951.7826519541127, 3963.227170701743, 3966.6033396660105, 6468.353164683588, 7842.686292548234, 7856.84286314282, 7882.921170788333, 7904.367650587939, 7751.108622508484, 7760.534367937829, 7764.145943946161, 7777.183395743478, 7791.376689864797, 7867.881981770278, 7877.369810415267, 7895.6052197391045, 7898.526088434713, 7915.0, 7269.709211631475, 7494.288042635941, 7500.099988001475, 7528.473006889465, 7597.088349398107, 6348.714307856065, 7083.893741593777, 7452.590107543921, 7455.925440745523, 7484.064491938508, 7903.881441778493, 7904.486208396436, 7916.485428447132, 7929.167437419219, 7935.563544005136, 4614.0, 4671.766411679335, 4679.625629949528, 4685.39089918322, 4702.52974702527, 7221.386182174574, 7472.738454154079, 7487.663055162422, 7491.288327608869, 7511.9624401877645, 7196.460265480006, 7224.241454647186, 7256.7822965311125, 7259.310365515267, 7291.197968223385, 7198.6400679964745, 7224.0, 7226.313500217471, 7237.601931893586, 7240.841465365516, 6969.059177011241, 7024.2975702431395, 7037.064807178067, 7050.294970502909, 7055.611941343275, 6958.199807022257, 7212.783616491513, 7221.494495385273, 7234.385077268491, 7235.372041290878, 6338.968305158444, 6490.902636460362, 6936.410405115622, 7059.517501324779, 7133.215346312006], "value": 5224.705527545795, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.7614058938784, 684.5789839248862, 749.093596747935, 752.4995877741302, 760.2853317881192, 11734.237274577146, 14968.401263949429, 15267.541973740777, 15289.311980960856, 15293.617659558517, 13341.13282636628, 13400.396982135802, 13403.46386144553, 13426.99297552685, 13432.66418339542, 717.0892965933261, 884.3809333466567, 888.1473785166248, 919.3932004876783, 919.8988111307751, 900.5767289373983, 903.8011637439786, 916.7983043730369, 917.4311926605513, 917.6512925088457, 940.9623615055424, 973.1582181413062, 976.3751199232497, 976.4336684722879, 977.3451787302489, 949.8765160529122, 959.0457494792726, 966.4201479112546, 968.9176420004308, 972.3485264872517, 616.3435940723116, 714.5248409807472, 722.7073035420663, 722.7217521254303, 727.2291371146601, 14505.202213878232, 14516.564503064925, 14535.200563968978, 14560.199189044704, 14604.561863144123, 11008.559657613685, 12530.498780048787, 12714.237145771283, 12876.034297427637, 13023.36180078066, 8530.274926631244, 10011.349262298, 10344.948275258575, 10533.841992370044, 10616.044555989998, 3432.3993301172445, 3446.638102999199, 3508.7543871928974, 3558.626344233844, 3737.495438115847, 82329.0, 82739.5863020681, 82850.0, 84156.57921710353, 85222.57388713078, 9336.346455748033, 9336.439813611205, 9363.485008324607, 9433.151016408556, 9470.95264523673, 20540.897295513725, 20717.0, 20790.896045519676, 20817.0, 20860.374188774058, 5798.362180160179, 6473.579217350857, 6513.0, 7384.667689953965, 7419.109706835215, 4018.2566225248424, 4145.813438395279, 4146.150039241948, 4157.729747566459, 4181.142865712522, 3371.8145501997137, 3378.527006219082, 3409.880654177092, 3423.9657603424143, 3435.381631306339, 535.2559941681067, 538.6902531044586, 545.8608054945963, 608.5223099866583, 617.7806878558137, 513.5506431872112, 547.7069767674354, 554.1272495819101, 565.21435205065, 604.4650484321319, 8030.357571394275, 8176.877346839802, 8239.711610093618, 8271.1728827118, 8510.276626486819, 576.2537513919401, 589.9262592175978, 592.4519819167339, 593.2821286243693, 619.3899009475663, 3760.2291530236234, 3806.923861522754, 3812.370958791824, 3827.25368553135, 3849.8075096245634, 91393.08606913846, 93249.06750932404, 96317.03682963221, 96775.0, 96949.0, 69230.61538769197, 69285.22151234202, 72015.0, 73349.02478149421, 73458.63270683613, 64774.676126619066, 66480.33519664838, 68161.65919170472, 69467.0, 70474.0, 7631.38948884099, 7664.080310362792, 7688.538687678533, 7716.5370077795515, 7798.103218129988, 50308.74845625748, 50597.494025060754, 50616.0, 50816.23775643294, 51349.48650513597, 51433.0, 64190.037149441865, 68518.65740671265, 68767.65616171887, 69539.65230173817, 9776.951115244377, 9848.950755246464, 9995.450250236307, 10087.596496140071, 10102.444365559964, 10571.101456376295, 10629.574817007233, 10794.73013174677, 10813.837792433276, 11216.046636035702, 10134.138598218939, 10180.643677471253, 10363.378197308186, 10379.0, 10710.625128120479, 10028.69913902584, 10169.898301016896, 10435.94782026085, 10774.892251077388, 10780.407077610807, 10207.795844082928, 11085.944570277097, 11250.66248012561, 11315.547378105113, 11787.587434439753, 9370.812583748151, 9494.851978840568, 9519.619215231312, 9575.329726919048, 9670.226381889574, 8321.791955201168, 8815.633576795606, 9441.952790236004, 9861.65484208078, 9938.552765125442], "value": 6705.593173853803, "unit": "score"}], "name": "dromaeo_css", "value": 7449.685517359735, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 118.0, 109.0, 119.0, 115.0, 119.0, 106.0, 118.0, 116.0, 116.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 127.0, 161.0, 155.0, 124.0, 151.0, 144.0, 123.0, 169.0, 120.0], "value": 142.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [213.0, 278.0, 223.0, 200.0, 201.0, 200.0, 237.0, 195.0, 197.0, 193.0], "value": 213.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 111.0, 107.0, 171.0, 59.0, 56.0, 58.0, 64.0, 123.0, 57.0], "value": 87.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 82.0, 132.0, 77.0, 80.0, 78.0, 118.0, 79.0, 80.0, 117.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 97.0, 138.0, 86.0, 88.0, 85.0, 108.0, 131.0], "value": 99.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 150.0, 103.0, 151.0, 130.0, 103.0, 102.0, 93.0, 94.0, 96.0], "value": 111.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 148.0, 90.0, 118.0, 83.0, 89.0, 91.0, 90.0, 91.0, 91.0], "value": 102.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 64.0, 66.0, 66.0, 66.0, 58.0, 54.0, 110.0, 99.0, 94.0], "value": 73.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 69.0, 70.0, 73.0, 73.0, 75.0, 70.0, 70.0, 71.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 81.0, 83.0, 83.0, 84.0, 82.0, 82.0, 83.0, 88.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 127.0, 133.0, 131.0, 132.0, 130.0, 132.0, 143.0, 156.0, 170.0], "value": 138.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 185.0, 177.0, 169.0, 170.0, 169.0, 168.0, 168.0, 206.0, 168.0], "value": 179.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 73.0, 69.0, 63.0, 63.0, 83.0, 81.0, 101.0, 99.0, 101.0], "value": 79.2, "unit": "score"}], "name": "kraken", "value": 1588.1000000000001, "alertThreshold": 2.0}]} 17:39:43 INFO - Return code: 0 17:39:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:39:43 ERROR - # TBPL SUCCESS # 17:39:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:39:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:39:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:39:43 INFO - 2016-05-27 17:39:43.083 screenresolution[1688:11373] starting screenresolution argv=screenresolution get 17:39:43 INFO - 2016-05-27 17:39:43.086 screenresolution[1688:11373] Display 0: 1600x1200x32@60 17:39:43 INFO - 2016-05-27 17:39:43.092 screenresolution[1689:11377] starting screenresolution argv=screenresolution list 17:39:43 INFO - Available Modes on Display 0 17:39:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:39:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:39:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:39:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:39:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:39:43 INFO - Intel Iris: 17:39:43 INFO - Chipset Model: Intel Iris 17:39:43 INFO - Type: GPU 17:39:43 INFO - Bus: Built-In 17:39:43 INFO - VRAM (Dynamic, Max): 1536 MB 17:39:43 INFO - Vendor: Intel (0x8086) 17:39:43 INFO - Device ID: 0x0a2e 17:39:43 INFO - Revision ID: 0x0009 17:39:43 INFO - Displays: 17:39:43 INFO - Display: 17:39:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:39:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:39:43 INFO - Display Serial Number: 1600x1200 60 17:39:43 INFO - Main Display: Yes 17:39:43 INFO - Mirror: Off 17:39:43 INFO - Online: Yes 17:39:43 INFO - Rotation: Supported 17:39:43 INFO - Return code: 0 17:39:43 INFO - Running post-action listener: _resource_record_post_action 17:39:43 INFO - Running post-run listener: _resource_record_post_run 17:39:44 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 31669760; Write bytes: 656618496; Read time: 198; Write time: 1904 17:39:44 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 237568; Write bytes: 356120064; Read time: 3; Write time: 778 17:39:44 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 29888000; Write bytes: 299650560; Read time: 183; Write time: 1124 17:39:44 INFO - Running post-run listener: _upload_blobber_files 17:39:44 INFO - Blob upload gear active. 17:39:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:39:44 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 17:39:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:39:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:39:44 INFO - (blobuploader) - INFO - Open directory for files ... 17:39:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:39:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:39:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:39:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:39:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:39:45 INFO - (blobuploader) - INFO - Done attempting. 17:39:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:39:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:39:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:39:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:39:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:39:46 INFO - (blobuploader) - INFO - Done attempting. 17:39:46 INFO - (blobuploader) - INFO - Iteration through files over. 17:39:46 INFO - Return code: 0 17:39:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:39:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:39:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4100c36797cdf1ec130312bccb00169f2ac635567d1e9308c63f0c463431f6f447d756adffed48aec3055a2207ae3228d3f0baf9f2b3fc985778ce9fe5322529", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab395d63980a486e809386182be5a64a44bb678b4876ff4c21ded246b8f7411725995a561d47aef4649b8c2f5623c805889beea6fb05074302ebc8c962ba7d41"} 17:39:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:39:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:39:46 INFO - Contents: 17:39:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4100c36797cdf1ec130312bccb00169f2ac635567d1e9308c63f0c463431f6f447d756adffed48aec3055a2207ae3228d3f0baf9f2b3fc985778ce9fe5322529", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab395d63980a486e809386182be5a64a44bb678b4876ff4c21ded246b8f7411725995a561d47aef4649b8c2f5623c805889beea6fb05074302ebc8c962ba7d41"} 17:39:46 INFO - Running post-run listener: copy_logs_to_upload_dir 17:39:46 INFO - Copying logs to upload dir... 17:39:46 INFO - mkdir: /builds/slave/test/build/upload/logs 17:39:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.887686 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-05-27 17:39:47.055486) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 17:39:47.058944) ========= 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.2beiD978Cp/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.XgAiCgAHvd/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4100c36797cdf1ec130312bccb00169f2ac635567d1e9308c63f0c463431f6f447d756adffed48aec3055a2207ae3228d3f0baf9f2b3fc985778ce9fe5322529", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab395d63980a486e809386182be5a64a44bb678b4876ff4c21ded246b8f7411725995a561d47aef4649b8c2f5623c805889beea6fb05074302ebc8c962ba7d41"} build_url:https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011089 build_url: 'https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4100c36797cdf1ec130312bccb00169f2ac635567d1e9308c63f0c463431f6f447d756adffed48aec3055a2207ae3228d3f0baf9f2b3fc985778ce9fe5322529", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab395d63980a486e809386182be5a64a44bb678b4876ff4c21ded246b8f7411725995a561d47aef4649b8c2f5623c805889beea6fb05074302ebc8c962ba7d41"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 17:39:47.089049) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 17:39:47.089380) ========= 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.2beiD978Cp/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.XgAiCgAHvd/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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.005110 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 17:39:47.137687) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 17:39:47.138061) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 17:39:47.138481) ========= ========= Total master_lag: 0.21 =========