builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0123 starttime: 1462583061.06 results: success (0) buildid: 20160506161844 builduid: af4cb0eabbd54d7d9439215173f25315 revision: ddb972fb7c3721d82fb05774bbaf41806f13e545 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:21.063993) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:21.064343) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:21.064620) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:21.085842) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:21.086113) ========= 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.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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-06 18:04:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.21M=0.001s 2016-05-06 18:04:21 (8.21 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.487798 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:21.594189) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:21.594477) ========= 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.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.020430 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:21.642033) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:21.642354) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ddb972fb7c3721d82fb05774bbaf41806f13e545 --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 ddb972fb7c3721d82fb05774bbaf41806f13e545 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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-06 18:04:21,714 truncating revision to first 12 chars 2016-05-06 18:04:21,714 Setting DEBUG logging. 2016-05-06 18:04:21,714 attempt 1/10 2016-05-06 18:04:21,714 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ddb972fb7c37?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 18:04:22,304 unpacking tar archive at: mozilla-aurora-ddb972fb7c37/testing/mozharness/ program finished with exit code 0 elapsedTime=0.839703 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:22.498275) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:22.498544) ========= script_repo_revision: ddb972fb7c3721d82fb05774bbaf41806f13e545 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:22.498890) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:22.499142) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:22.514806) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-06 18:04:22.515131) ========= /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.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:04:22 INFO - MultiFileLogger online at 20160506 18:04:22 in /builds/slave/test 18:04:22 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 18:04:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:04:22 INFO - {'append_to_log': False, 18:04:22 INFO - 'base_work_dir': '/builds/slave/test', 18:04:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:04:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:04:22 INFO - 'branch': 'Mozilla-Aurora', 18:04:22 INFO - 'buildbot_json_path': 'buildprops.json', 18:04:22 INFO - 'config_files': ('talos/mac_config.py',), 18:04:22 INFO - 'default_actions': ('clobber', 18:04:22 INFO - 'read-buildbot-config', 18:04:22 INFO - 'download-and-extract', 18:04:22 INFO - 'populate-webroot', 18:04:22 INFO - 'create-virtualenv', 18:04:22 INFO - 'install', 18:04:22 INFO - 'run-tests'), 18:04:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:04:22 INFO - 'download_minidump_stackwalk': True, 18:04:22 INFO - 'download_symbols': 'ondemand', 18:04:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:04:22 INFO - 'tooltool.py': '/tools/tooltool.py', 18:04:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:04:22 INFO - '/tools/misc-python/virtualenv.py')}, 18:04:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:04:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:04:22 INFO - 'installer_path': 'installer.exe', 18:04:22 INFO - 'log_level': 'info', 18:04:22 INFO - 'log_name': 'talos', 18:04:22 INFO - 'log_to_console': True, 18:04:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:04:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:04:22 INFO - 'opt_config_files': (), 18:04:22 INFO - 'pip_index': False, 18:04:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:04:22 INFO - 'cmd': ('bash', 18:04:22 INFO - '-c', 18:04:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:04:22 INFO - 'enabled': True, 18:04:22 INFO - 'halt_on_failure': False, 18:04:22 INFO - 'name': 'check_screen_resolution'},), 18:04:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:04:22 INFO - 'cmd': ('bash', 18:04:22 INFO - '-c', 18:04:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:04:22 INFO - 'enabled': True, 18:04:22 INFO - 'halt_on_failure': False, 18:04:22 INFO - 'name': 'check_screen_resolution'},), 18:04:22 INFO - 'run_cmd_checks_enabled': True, 18:04:22 INFO - 'sps_profile': False, 18:04:22 INFO - 'sps_profile_interval': 0, 18:04:22 INFO - 'suite': 'dromaeojs-e10s', 18:04:22 INFO - 'system_bits': '32', 18:04:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:04:22 INFO - 'title': 't-yosemite-r7-0123', 18:04:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:04:22 INFO - 'use_talos_json': True, 18:04:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:04:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:04:22 INFO - 'work_dir': 'build'} 18:04:22 INFO - ##### 18:04:22 INFO - ##### Running clobber step. 18:04:22 INFO - ##### 18:04:22 INFO - Running pre-action listener: _resource_record_pre_action 18:04:22 INFO - Running main action method: clobber 18:04:22 INFO - rmtree: /builds/slave/test/build 18:04:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:04:24 INFO - Running post-action listener: _resource_record_post_action 18:04:24 INFO - ##### 18:04:24 INFO - ##### Running read-buildbot-config step. 18:04:24 INFO - ##### 18:04:24 INFO - Running pre-action listener: _resource_record_pre_action 18:04:24 INFO - Running main action method: read_buildbot_config 18:04:24 INFO - Using buildbot properties: 18:04:24 INFO - { 18:04:24 INFO - "project": "", 18:04:24 INFO - "product": "firefox", 18:04:24 INFO - "script_repo_revision": "production", 18:04:24 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 18:04:24 INFO - "repository": "", 18:04:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 18:04:24 INFO - "buildid": "20160506161844", 18:04:24 INFO - "pgo_build": "False", 18:04:24 INFO - "basedir": "/builds/slave/test", 18:04:24 INFO - "buildnumber": 4, 18:04:24 INFO - "slavename": "t-yosemite-r7-0123", 18:04:24 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:04:24 INFO - "platform": "yosemite_r7", 18:04:24 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:04:24 INFO - "branch": "mozilla-aurora", 18:04:24 INFO - "repo_path": "releases/mozilla-aurora", 18:04:24 INFO - "revision": "ddb972fb7c3721d82fb05774bbaf41806f13e545", 18:04:24 INFO - "stage_platform": "macosx64", 18:04:24 INFO - "builduid": "af4cb0eabbd54d7d9439215173f25315", 18:04:24 INFO - "slavebuilddir": "test" 18:04:24 INFO - } 18:04:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 18:04:24 INFO - Running post-action listener: _resource_record_post_action 18:04:24 INFO - ##### 18:04:24 INFO - ##### Running download-and-extract step. 18:04:24 INFO - ##### 18:04:24 INFO - Running pre-action listener: _resource_record_pre_action 18:04:24 INFO - Running main action method: download_and_extract 18:04:24 INFO - mkdir: /builds/slave/test/build/tests 18:04:24 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')]} 18:04:24 INFO - https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:04:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:04:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:04:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/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 18:04:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/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 18:04:27 INFO - Downloaded 1199 bytes. 18:04:27 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 18:04:27 INFO - Using the following test package requirements: 18:04:27 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:04:27 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:27 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 18:04:27 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:27 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 18:04:27 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:27 INFO - u'jsshell-mac.zip'], 18:04:27 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:27 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 18:04:27 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:04:27 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:27 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 18:04:27 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:27 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 18:04:27 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:27 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 18:04:27 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:27 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 18:04:27 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:04:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:04:27 INFO - https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:04:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:04:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:04:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/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 18:04:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/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 18:04:29 INFO - Downloaded 18088827 bytes. 18:04:29 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'] 18:04:29 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 18:04:32 INFO - Return code: 0 18:04:32 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 18:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:04:32 INFO - https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:04:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:04:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:04:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/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 18:04:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/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 18:04:33 INFO - Downloaded 18088827 bytes. 18:04:33 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'] 18:04:33 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 18:04:37 INFO - Return code: 0 18:04:37 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')]} 18:04:37 INFO - https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:04:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:04:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:04:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/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 18:04:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/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 18:04:38 INFO - Downloaded 10840145 bytes. 18:04:38 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'] 18:04:38 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 18:04:38 INFO - Return code: 0 18:04:38 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')]} 18:04:38 INFO - https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 18:04:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:04:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:04:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 18:04:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/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 18:04:41 INFO - Downloaded 114088008 bytes. 18:04:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:04:41 INFO - mkdir: /builds/slave/test/properties 18:04:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:04:41 INFO - Writing to file /builds/slave/test/properties/build_url 18:04:41 INFO - Contents: 18:04:41 INFO - build_url:https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:04:42 INFO - Running post-action listener: _resource_record_post_action 18:04:42 INFO - Running post-action listener: set_extra_try_arguments 18:04:42 INFO - ##### 18:04:42 INFO - ##### Running populate-webroot step. 18:04:42 INFO - ##### 18:04:42 INFO - Running pre-action listener: _resource_record_pre_action 18:04:42 INFO - Running main action method: populate_webroot 18:04:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:04:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:04:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:04:42 INFO - '--tppagecycles', 18:04:42 INFO - '7'], 18:04:42 INFO - 'tests': ['tsvgm']}}, 18:04:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:04:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:04:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:04:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:04:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:04:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:04:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:42 INFO - 'talos_options': ['--disable-e10s'], 18:04:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:42 INFO - 'talos_options': ['--disable-e10s'], 18:04:42 INFO - 'tests': ['damp', 'tps']}, 18:04:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:42 INFO - 'tests': ['damp', 'tps']}, 18:04:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:04:42 INFO - 'tests': ['dromaeo_dom']}, 18:04:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:04:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:04:42 INFO - 'tests': ['a11yr', 18:04:42 INFO - 'ts_paint', 18:04:42 INFO - 'tpaint', 18:04:42 INFO - 'sessionrestore', 18:04:42 INFO - 'sessionrestore_no_auto_restore', 18:04:43 INFO - 'tabpaint']}, 18:04:43 INFO - 'other-e10s': {'tests': ['a11yr', 18:04:43 INFO - 'ts_paint', 18:04:43 INFO - 'tpaint', 18:04:43 INFO - 'sessionrestore', 18:04:43 INFO - 'sessionrestore_no_auto_restore', 18:04:43 INFO - 'tabpaint']}, 18:04:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:04:43 INFO - 'ts_paint', 18:04:43 INFO - 'tpaint', 18:04:43 INFO - 'sessionrestore', 18:04:43 INFO - 'sessionrestore_no_auto_restore', 18:04:43 INFO - 'tabpaint']}, 18:04:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:04:43 INFO - 'ts_paint', 18:04:43 INFO - 'tpaint', 18:04:43 INFO - 'sessionrestore', 18:04:43 INFO - 'sessionrestore_no_auto_restore', 18:04:43 INFO - 'tabpaint']}, 18:04:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:04:43 INFO - 'tests': ['a11yr', 18:04:43 INFO - 'ts_paint', 18:04:43 INFO - 'tpaint', 18:04:43 INFO - 'sessionrestore', 18:04:43 INFO - 'sessionrestore_no_auto_restore', 18:04:43 INFO - 'tabpaint']}, 18:04:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:04:43 INFO - 'tests': ['a11yr', 18:04:43 INFO - 'ts_paint', 18:04:43 INFO - 'tpaint', 18:04:43 INFO - 'sessionrestore', 18:04:43 INFO - 'sessionrestore_no_auto_restore', 18:04:43 INFO - 'tabpaint']}, 18:04:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:04:43 INFO - 'tests': ['tsvgx', 18:04:43 INFO - 'tsvgr_opacity', 18:04:43 INFO - 'tart', 18:04:43 INFO - 'tscrollx', 18:04:43 INFO - 'cart']}, 18:04:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:04:43 INFO - 'tsvgr_opacity', 18:04:43 INFO - 'tart', 18:04:43 INFO - 'tscrollx', 18:04:43 INFO - 'cart']}, 18:04:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:43 INFO - 'talos_options': ['--disable-e10s'], 18:04:43 INFO - 'tests': ['tp5o']}, 18:04:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:43 INFO - 'tests': ['tp5o']}, 18:04:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:43 INFO - 'talos_options': ['--disable-e10s', 18:04:43 INFO - '--xperf_path', 18:04:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:43 INFO - 'tests': ['tp5n']}, 18:04:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:43 INFO - 'talos_options': ['--xperf_path', 18:04:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:43 INFO - 'tests': ['tp5n']}}, 18:04:43 INFO - 'talos.zip': {'path': '', 18:04:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:04:43 INFO - Running post-action listener: _resource_record_post_action 18:04:43 INFO - ##### 18:04:43 INFO - ##### Running create-virtualenv step. 18:04:43 INFO - ##### 18:04:43 INFO - Running pre-action listener: _resource_record_pre_action 18:04:43 INFO - Running main action method: create_virtualenv 18:04:43 INFO - Creating virtualenv /builds/slave/test/build/venv 18:04:43 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 18:04:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:04:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:04:43 INFO - Using real prefix '/tools/python27' 18:04:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:04:43 INFO - Installing distribute.............................................................................................................................................................................................done. 18:04:46 INFO - Installing pip.................done. 18:04:46 INFO - Return code: 0 18:04:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:04:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:46 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaabcaead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110059e00>, '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 0x10fec2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110054b20>, '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.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:04:46 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 18:04:46 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 18:04:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2jpqFi1jXX/Render', 18:04:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:46 INFO - 'HOME': '/Users/cltbld', 18:04:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:46 INFO - 'LOGNAME': 'cltbld', 18:04:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:46 INFO - 'MOZ_NO_REMOTE': '1', 18:04:46 INFO - 'NO_EM_RESTART': '1', 18:04:46 INFO - 'PAGER': '/bin/cat', 18:04:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:46 INFO - 'PWD': '/builds/slave/test', 18:04:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:46 INFO - 'SHELL': '/bin/bash', 18:04:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLQXHYNsjQ/Listeners', 18:04:46 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:04:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:46 INFO - 'USER': 'cltbld', 18:04:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:46 INFO - 'XPC_FLAGS': '0x0', 18:04:46 INFO - 'XPC_SERVICE_NAME': '0', 18:04:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:46 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 18:04:46 INFO - Cleaning up... 18:04:46 INFO - Return code: 0 18:04:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:04:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:46 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaabcaead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110059e00>, '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 0x10fec2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110054b20>, '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.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:04:46 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 18:04:46 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 18:04:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2jpqFi1jXX/Render', 18:04:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:46 INFO - 'HOME': '/Users/cltbld', 18:04:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:46 INFO - 'LOGNAME': 'cltbld', 18:04:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:46 INFO - 'MOZ_NO_REMOTE': '1', 18:04:46 INFO - 'NO_EM_RESTART': '1', 18:04:46 INFO - 'PAGER': '/bin/cat', 18:04:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:46 INFO - 'PWD': '/builds/slave/test', 18:04:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:46 INFO - 'SHELL': '/bin/bash', 18:04:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLQXHYNsjQ/Listeners', 18:04:46 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:04:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:46 INFO - 'USER': 'cltbld', 18:04:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:46 INFO - 'XPC_FLAGS': '0x0', 18:04:46 INFO - 'XPC_SERVICE_NAME': '0', 18:04:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:47 INFO - Downloading/unpacking psutil>=0.7.1 18:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:04:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:04:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:04:50 INFO - Installing collected packages: psutil 18:04:50 INFO - Running setup.py install for psutil 18:04:50 INFO - building 'psutil._psutil_osx' extension 18:04:50 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 18:04:50 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 18:04:51 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 18:04:51 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 18:04:51 INFO - building 'psutil._psutil_posix' extension 18:04:51 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 18:04:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:04:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:04:51 INFO - ^ 18:04:51 INFO - 1 warning generated. 18:04:51 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 18:04:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:04:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:04:51 INFO - Successfully installed psutil 18:04:51 INFO - Cleaning up... 18:04:51 INFO - Return code: 0 18:04:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:04:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaabcaead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110059e00>, '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 0x10fec2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110054b20>, '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.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:04:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:04:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:04:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2jpqFi1jXX/Render', 18:04:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:51 INFO - 'HOME': '/Users/cltbld', 18:04:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:51 INFO - 'LOGNAME': 'cltbld', 18:04:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:51 INFO - 'MOZ_NO_REMOTE': '1', 18:04:51 INFO - 'NO_EM_RESTART': '1', 18:04:51 INFO - 'PAGER': '/bin/cat', 18:04:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:51 INFO - 'PWD': '/builds/slave/test', 18:04:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:51 INFO - 'SHELL': '/bin/bash', 18:04:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLQXHYNsjQ/Listeners', 18:04:51 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:04:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:51 INFO - 'USER': 'cltbld', 18:04:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:51 INFO - 'XPC_FLAGS': '0x0', 18:04:51 INFO - 'XPC_SERVICE_NAME': '0', 18:04:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:04:54 INFO - Installing collected packages: mozsystemmonitor 18:04:54 INFO - Running setup.py install for mozsystemmonitor 18:04:54 INFO - Successfully installed mozsystemmonitor 18:04:54 INFO - Cleaning up... 18:04:54 INFO - Return code: 0 18:04:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:54 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaabcaead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110059e00>, '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 0x10fec2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110054b20>, '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.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:04:54 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 18:04:54 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 18:04:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2jpqFi1jXX/Render', 18:04:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:54 INFO - 'HOME': '/Users/cltbld', 18:04:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:54 INFO - 'LOGNAME': 'cltbld', 18:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:54 INFO - 'MOZ_NO_REMOTE': '1', 18:04:54 INFO - 'NO_EM_RESTART': '1', 18:04:54 INFO - 'PAGER': '/bin/cat', 18:04:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:54 INFO - 'PWD': '/builds/slave/test', 18:04:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:54 INFO - 'SHELL': '/bin/bash', 18:04:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLQXHYNsjQ/Listeners', 18:04:54 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:04:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:54 INFO - 'USER': 'cltbld', 18:04:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:54 INFO - 'XPC_FLAGS': '0x0', 18:04:54 INFO - 'XPC_SERVICE_NAME': '0', 18:04:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:54 INFO - Downloading/unpacking blobuploader==1.2.4 18:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:56 INFO - Downloading blobuploader-1.2.4.tar.gz 18:04:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:04:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:04:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:04:58 INFO - Downloading docopt-0.6.1.tar.gz 18:04:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:04:58 INFO - Installing collected packages: blobuploader, requests, docopt 18:04:58 INFO - Running setup.py install for blobuploader 18:04:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:04:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:04:58 INFO - Running setup.py install for requests 18:04:58 INFO - Running setup.py install for docopt 18:04:59 INFO - Successfully installed blobuploader requests docopt 18:04:59 INFO - Cleaning up... 18:04:59 INFO - Return code: 0 18:04:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:04:59 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')]} 18:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:59 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')]} 18:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:59 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaabcaead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110059e00>, '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 0x10fec2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110054b20>, '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.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:04:59 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 18:04:59 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 18:04:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2jpqFi1jXX/Render', 18:04:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:59 INFO - 'HOME': '/Users/cltbld', 18:04:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:59 INFO - 'LOGNAME': 'cltbld', 18:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:59 INFO - 'MOZ_NO_REMOTE': '1', 18:04:59 INFO - 'NO_EM_RESTART': '1', 18:04:59 INFO - 'PAGER': '/bin/cat', 18:04:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:59 INFO - 'PWD': '/builds/slave/test', 18:04:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:59 INFO - 'SHELL': '/bin/bash', 18:04:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLQXHYNsjQ/Listeners', 18:04:59 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:04:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:59 INFO - 'USER': 'cltbld', 18:04:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:59 INFO - 'XPC_FLAGS': '0x0', 18:04:59 INFO - 'XPC_SERVICE_NAME': '0', 18:04:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:04:59 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-FTBIMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:04:59 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-mK_Lsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:04:59 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-92JWbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:04:59 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-KzA_X3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:04:59 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-6HrN17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:04:59 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-W1xOCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-qfzfKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-KWYCcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-4RPlPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-GdeVBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-TRYIND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-0TF9kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-9Pr8Kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-JCViCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-GvKyP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-wZLI_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-7ssu47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:05:01 INFO - Running setup.py install for manifestparser 18:05:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Running setup.py install for mozcrash 18:05:01 INFO - Running setup.py install for mozdebug 18:05:01 INFO - Running setup.py install for mozdevice 18:05:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Running setup.py install for mozfile 18:05:01 INFO - Running setup.py install for mozhttpd 18:05:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Running setup.py install for mozinfo 18:05:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Running setup.py install for mozInstall 18:05:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Running setup.py install for mozleak 18:05:02 INFO - Running setup.py install for mozlog 18:05:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Running setup.py install for moznetwork 18:05:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Running setup.py install for mozprocess 18:05:02 INFO - Running setup.py install for mozprofile 18:05:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:05:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:05:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:05:03 INFO - Running setup.py install for mozrunner 18:05:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:05:03 INFO - Running setup.py install for mozscreenshot 18:05:03 INFO - Running setup.py install for moztest 18:05:03 INFO - Running setup.py install for mozversion 18:05:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:05:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:05:03 INFO - Cleaning up... 18:05:03 INFO - Return code: 0 18:05:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:03 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')]} 18:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:03 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')]} 18:05:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:03 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaabcaead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110059e00>, '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 0x10fec2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110054b20>, '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.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:05:03 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 18:05:03 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 18:05:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2jpqFi1jXX/Render', 18:05:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:03 INFO - 'HOME': '/Users/cltbld', 18:05:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:03 INFO - 'LOGNAME': 'cltbld', 18:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:03 INFO - 'MOZ_NO_REMOTE': '1', 18:05:03 INFO - 'NO_EM_RESTART': '1', 18:05:03 INFO - 'PAGER': '/bin/cat', 18:05:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:03 INFO - 'PWD': '/builds/slave/test', 18:05:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:03 INFO - 'SHELL': '/bin/bash', 18:05:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLQXHYNsjQ/Listeners', 18:05:03 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:05:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:03 INFO - 'USER': 'cltbld', 18:05:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:03 INFO - 'XPC_FLAGS': '0x0', 18:05:03 INFO - 'XPC_SERVICE_NAME': '0', 18:05:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-bDYbOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ZIYc0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-OnX76l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-dVAP1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ylAQSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-mGCZFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-INyCjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-xaXv0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-V1I9j1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ceaDeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:05 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)) 18:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-W_FWJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:05 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)) 18:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-fYLn78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:05 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)) 18:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-HNDy7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:05 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)) 18:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ydOQ2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:05 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)) 18:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-YcYQob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:05 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)) 18:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-sIM0Em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:05 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)) 18:05:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-YP31b4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:05:05 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 18:05:05 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 18:05:05 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 18:05:05 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 18:05:05 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 18:05:05 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 18:05:08 INFO - Downloading blessings-1.6.tar.gz 18:05:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:05:08 INFO - Installing collected packages: blessings 18:05:08 INFO - Running setup.py install for blessings 18:05:08 INFO - Successfully installed blessings 18:05:08 INFO - Cleaning up... 18:05:08 INFO - Return code: 0 18:05:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:05:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:08 INFO - Reading from file tmpfile_stdout 18:05:08 INFO - Current package versions: 18:05:08 INFO - blessings == 1.6 18:05:08 INFO - blobuploader == 1.2.4 18:05:08 INFO - docopt == 0.6.1 18:05:08 INFO - manifestparser == 1.1 18:05:08 INFO - mozInstall == 1.12 18:05:08 INFO - mozcrash == 0.17 18:05:08 INFO - mozdebug == 0.1 18:05:08 INFO - mozdevice == 0.48 18:05:08 INFO - mozfile == 1.2 18:05:08 INFO - mozhttpd == 0.7 18:05:08 INFO - mozinfo == 0.9 18:05:08 INFO - mozleak == 0.1 18:05:08 INFO - mozlog == 3.1 18:05:08 INFO - moznetwork == 0.27 18:05:08 INFO - mozprocess == 0.22 18:05:08 INFO - mozprofile == 0.28 18:05:08 INFO - mozrunner == 6.11 18:05:08 INFO - mozscreenshot == 0.1 18:05:08 INFO - mozsystemmonitor == 0.0 18:05:08 INFO - moztest == 0.7 18:05:08 INFO - mozversion == 1.4 18:05:08 INFO - psutil == 3.1.1 18:05:08 INFO - requests == 1.2.3 18:05:08 INFO - wsgiref == 0.1.2 18:05:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:08 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')]} 18:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:08 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')]} 18:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:08 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaabcaead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110059e00>, '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 0x10fec2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110054b20>, '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.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:05:08 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 18:05:08 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 18:05:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2jpqFi1jXX/Render', 18:05:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:08 INFO - 'HOME': '/Users/cltbld', 18:05:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:08 INFO - 'LOGNAME': 'cltbld', 18:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:08 INFO - 'MOZ_NO_REMOTE': '1', 18:05:08 INFO - 'NO_EM_RESTART': '1', 18:05:08 INFO - 'PAGER': '/bin/cat', 18:05:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:08 INFO - 'PWD': '/builds/slave/test', 18:05:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:08 INFO - 'SHELL': '/bin/bash', 18:05:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLQXHYNsjQ/Listeners', 18:05:08 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:05:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:08 INFO - 'USER': 'cltbld', 18:05:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:08 INFO - 'XPC_FLAGS': '0x0', 18:05:08 INFO - 'XPC_SERVICE_NAME': '0', 18:05:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:09 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)) 18:05:09 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)) 18:05:09 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)) 18:05:09 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)) 18:05:09 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)) 18:05:09 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)) 18:05:09 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)) 18:05:09 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)) 18:05:09 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)) 18:05:09 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)) 18:05:09 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)) 18:05:09 INFO - Cleaning up... 18:05:09 INFO - Return code: 0 18:05:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:05:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:05:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:09 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaabcaead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110059e00>, '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 0x10fec2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110054b20>, '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.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:05:09 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 18:05:09 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 18:05:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2jpqFi1jXX/Render', 18:05:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:09 INFO - 'HOME': '/Users/cltbld', 18:05:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:09 INFO - 'LOGNAME': 'cltbld', 18:05:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:09 INFO - 'MOZ_NO_REMOTE': '1', 18:05:09 INFO - 'NO_EM_RESTART': '1', 18:05:09 INFO - 'PAGER': '/bin/cat', 18:05:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:09 INFO - 'PWD': '/builds/slave/test', 18:05:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:09 INFO - 'SHELL': '/bin/bash', 18:05:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLQXHYNsjQ/Listeners', 18:05:09 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:05:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:09 INFO - 'USER': 'cltbld', 18:05:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:09 INFO - 'XPC_FLAGS': '0x0', 18:05:09 INFO - 'XPC_SERVICE_NAME': '0', 18:05:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:09 INFO - Downloading/unpacking jsonschema 18:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:09 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 18:05:09 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 18:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:05:11 INFO - Downloading/unpacking functools32 (from jsonschema) 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:12 INFO - Downloading functools32-3.2.3-2.tar.gz 18:05:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:05:12 INFO - warning: no files found matching '*.txt' 18:05:12 INFO - no previously-included directories found matching 'build' 18:05:12 INFO - no previously-included directories found matching 'dist' 18:05:12 INFO - no previously-included directories found matching '.git*' 18:05:12 INFO - Installing collected packages: jsonschema, functools32 18:05:12 INFO - Running setup.py install for functools32 18:05:12 INFO - warning: no files found matching '*.txt' 18:05:12 INFO - no previously-included directories found matching 'build' 18:05:12 INFO - no previously-included directories found matching 'dist' 18:05:12 INFO - no previously-included directories found matching '.git*' 18:05:12 INFO - Successfully installed jsonschema functools32 18:05:12 INFO - Cleaning up... 18:05:12 INFO - Return code: 0 18:05:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:05:12 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')]} 18:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:12 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')]} 18:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:12 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101ca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaabcaead0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110059e00>, '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 0x10fec2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110054b20>, '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.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:05:12 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 18:05:12 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 18:05:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2jpqFi1jXX/Render', 18:05:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:12 INFO - 'HOME': '/Users/cltbld', 18:05:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:12 INFO - 'LOGNAME': 'cltbld', 18:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:12 INFO - 'MOZ_NO_REMOTE': '1', 18:05:12 INFO - 'NO_EM_RESTART': '1', 18:05:12 INFO - 'PAGER': '/bin/cat', 18:05:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:12 INFO - 'PWD': '/builds/slave/test', 18:05:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:12 INFO - 'SHELL': '/bin/bash', 18:05:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLQXHYNsjQ/Listeners', 18:05:12 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:05:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:12 INFO - 'USER': 'cltbld', 18:05:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:12 INFO - 'XPC_FLAGS': '0x0', 18:05:12 INFO - 'XPC_SERVICE_NAME': '0', 18:05:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:12 INFO - Downloading/unpacking flake8 18:05:12 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 18:05:12 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 18:05:12 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 18:05:12 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 18:05:12 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 18:05:12 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 18:05:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:05:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:05:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:05:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:05:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:05:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:05:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:05:16 INFO - no previously-included directories found matching 'docs/_build' 18:05:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:05:16 INFO - Running setup.py install for pep8 18:05:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:05:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:05:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:05:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:05:16 INFO - no previously-included directories found matching 'docs/_build' 18:05:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:05:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:05:16 INFO - Cleaning up... 18:05:16 INFO - Return code: 0 18:05:16 INFO - Running post-action listener: _resource_record_post_action 18:05:16 INFO - Running post-action listener: _start_resource_monitoring 18:05:16 INFO - Starting resource monitoring. 18:05:16 INFO - ##### 18:05:16 INFO - ##### Running install step. 18:05:16 INFO - ##### 18:05:16 INFO - Running pre-action listener: _resource_record_pre_action 18:05:16 INFO - Running main action method: install 18:05:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:17 INFO - Reading from file tmpfile_stdout 18:05:17 INFO - Detecting whether we're running mozinstall >=1.0... 18:05:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:05:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:05:17 INFO - Reading from file tmpfile_stdout 18:05:17 INFO - Output received: 18:05:17 INFO - Usage: mozinstall [options] installer 18:05:17 INFO - Options: 18:05:17 INFO - -h, --help show this help message and exit 18:05:17 INFO - -d DEST, --destination=DEST 18:05:17 INFO - Directory to install application into. [default: 18:05:17 INFO - "/builds/slave/test"] 18:05:17 INFO - --app=APP Application being installed. [default: firefox] 18:05:17 INFO - mkdir: /builds/slave/test/build/application 18:05:17 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'] 18:05:17 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 18:05:48 INFO - Reading from file tmpfile_stdout 18:05:48 INFO - Output received: 18:05:48 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:05:48 INFO - Running post-action listener: _resource_record_post_action 18:05:48 INFO - ##### 18:05:48 INFO - ##### Running run-tests step. 18:05:48 INFO - ##### 18:05:48 INFO - Running pre-action listener: _resource_record_pre_action 18:05:48 INFO - Running main action method: run_tests 18:05:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:05:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:05:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:05:48 INFO - 2016-05-06 18:05:48.811 screenresolution[2473:20613] starting screenresolution argv=screenresolution get 18:05:48 INFO - 2016-05-06 18:05:48.814 screenresolution[2473:20613] Display 0: 1600x1200x32@60 18:05:48 INFO - 2016-05-06 18:05:48.820 screenresolution[2474:20615] starting screenresolution argv=screenresolution list 18:05:48 INFO - Available Modes on Display 0 18:05:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:05:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:05:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:05:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:05:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:05:49 INFO - Intel Iris: 18:05:49 INFO - Chipset Model: Intel Iris 18:05:49 INFO - Type: GPU 18:05:49 INFO - Bus: Built-In 18:05:49 INFO - VRAM (Dynamic, Max): 1536 MB 18:05:49 INFO - Vendor: Intel (0x8086) 18:05:49 INFO - Device ID: 0x0a2e 18:05:49 INFO - Revision ID: 0x0009 18:05:49 INFO - Displays: 18:05:49 INFO - Display: 18:05:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:05:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:05:49 INFO - Display Serial Number: 1600x1200 60 18:05:49 INFO - Main Display: Yes 18:05:49 INFO - Mirror: Off 18:05:49 INFO - Online: Yes 18:05:49 INFO - Rotation: Supported 18:05:49 INFO - Return code: 0 18:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:05:49 INFO - Python 2.7.3 18:05:49 INFO - Return code: 0 18:05:49 INFO - grabbing minidump binary from tooltool 18:05: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')]} 18:05:49 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 0x110059e00>, '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 0x10fec2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110054b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:05:49 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 18:05:49 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 18:05:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:05:49 INFO - Return code: 0 18:05:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:05:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:05:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:05:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:05:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:05:50 INFO - Return code: 0 18:05:50 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/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 18:05:50 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/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0123 --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 18:05:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2jpqFi1jXX/Render', 18:05:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:50 INFO - 'HOME': '/Users/cltbld', 18:05:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:50 INFO - 'LOGNAME': 'cltbld', 18:05:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:05:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:05:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:50 INFO - 'MOZ_NO_REMOTE': '1', 18:05:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:05:50 INFO - 'NO_EM_RESTART': '1', 18:05:50 INFO - 'PAGER': '/bin/cat', 18:05:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:50 INFO - 'PWD': '/builds/slave/test', 18:05:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:05:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:50 INFO - 'SHELL': '/bin/bash', 18:05:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLQXHYNsjQ/Listeners', 18:05:50 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:05:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:50 INFO - 'USER': 'cltbld', 18:05:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:50 INFO - 'XPC_FLAGS': '0x0', 18:05:50 INFO - 'XPC_SERVICE_NAME': '0', 18:05:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:50 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/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 18:05:50 INFO - mozversion application_buildid: 20160506161844 18:05:50 INFO - mozversion application_changeset: ddb972fb7c3721d82fb05774bbaf41806f13e545 18:05:50 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:05:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:05:50 INFO - mozversion application_name: Firefox 18:05:50 INFO - mozversion application_remotingname: firefox-dev 18:05:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:05:50 INFO - mozversion application_vendor: Mozilla 18:05:50 INFO - mozversion application_version: 48.0a2 18:05:50 INFO - mozversion platform_buildid: 20160506161844 18:05:50 INFO - mozversion platform_changeset: ddb972fb7c3721d82fb05774bbaf41806f13e545 18:05:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:05:50 INFO - mozversion platform_version: 48.0a2 18:05:50 INFO - using testdate: 1462583150 18:05:50 INFO - actual date: 1462583150 18:05:50 INFO - starting webserver on 'localhost:54657' 18:05:50 INFO - SUITE-START | Running 2 tests 18:05:50 INFO - TEST-START | dromaeo_css 18:05:50 INFO - operating with platform_type : mac_ 18:05:50 INFO - Initialising browser for dromaeo_css test... 18:05:50 INFO - TEST-INFO | started process 2492 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpCANLP6/profile http://localhost:54657/getInfo.html) 18:05:53 INFO - PROCESS | 2492 | __metrics Screen width/height:1600/1200 18:05:53 INFO - PROCESS | 2492 | colorDepth:24 18:05:53 INFO - PROCESS | 2492 | Browser inner width/height: 1024/689 18:05:53 INFO - PROCESS | 2492 | __metrics 18:05:53 ERROR - PROCESS | 2492 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 18:05:54 INFO - TEST-INFO | 2492: exit 0 18:05:54 INFO - Browser initialized. 18:05:54 INFO - Running cycle 1/1 for dromaeo_css test... 18:05:54 INFO - TEST-INFO | started process 2495 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpCANLP6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:06:06 INFO - PROCESS | 2495 | RSS: Main: 221753344 18:06:06 INFO - PROCESS | 2495 | 18:06:55 INFO - PROCESS | 2495 | 1462583215800 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] 18:09:07 INFO - PROCESS | 2495 | Cycle 1(1): loaded http://localhost:54657/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54657/tests/dromaeo/cssquery-ext.html) 18:09:08 INFO - PROCESS | 2495 | RSS: Main: 233558016 18:09:08 INFO - PROCESS | 2495 | 18:12:10 INFO - PROCESS | 2495 | Cycle 1(1): loaded http://localhost:54657/tests/dromaeo/cssquery-ext.html (next: http://localhost:54657/tests/dromaeo/cssquery-jquery.html) 18:12:11 INFO - PROCESS | 2495 | RSS: Main: 245161984 18:12:11 INFO - PROCESS | 2495 | 18:15:13 INFO - PROCESS | 2495 | Cycle 1(1): loaded http://localhost:54657/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54657/tests/dromaeo/cssquery-mootools.html) 18:15:13 INFO - PROCESS | 2495 | RSS: Main: 247246848 18:15:13 INFO - PROCESS | 2495 | 18:18:15 INFO - PROCESS | 2495 | Cycle 1(1): loaded http://localhost:54657/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54657/tests/dromaeo/cssquery-prototype.html) 18:18:16 INFO - PROCESS | 2495 | RSS: Main: 249704448 18:18:16 INFO - PROCESS | 2495 | 18:21:18 INFO - PROCESS | 2495 | Cycle 1(1): loaded http://localhost:54657/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54657/tests/dromaeo/cssquery-yui.html) 18:21:19 INFO - PROCESS | 2495 | RSS: Main: 252497920 18:21:19 INFO - PROCESS | 2495 | 18:24:21 INFO - PROCESS | 2495 | Cycle 1(1): loaded http://localhost:54657/tests/dromaeo/cssquery-yui.html (next: http://localhost:54657/tests/dromaeo/cssquery-dojo.html) 18:24:21 INFO - PROCESS | 2495 | RSS: Main: 252915712 18:24:21 INFO - PROCESS | 2495 | 18:24:21 INFO - PROCESS | 2495 | __start_tp_report 18:24:21 INFO - PROCESS | 2495 | _x_x_mozilla_page_load 18:24:21 INFO - PROCESS | 2495 | _x_x_mozilla_page_load_details 18:24:21 INFO - PROCESS | 2495 | |i|pagename|runs| 18:24:21 INFO - PROCESS | 2495 | |0;dojo.html;3455.2398472336085;3490.232148927235;3513.8243087845603;3543.202779374641;3550.236699109691;5539.113741801312;5727.026405511063;5769.221155144054;5771.278590176234;5823.650580965145;4799.856004319859;5587.938291724569;5804.709764511768;5848.561357898154;5886.205362276091;5278.049951008817;5293.8941221175555;5348.224507446418;5384.973075134619;5388.91916621251;5204.375474943012;5330.680159190441;5343.145096784515;5350.8127215547465;5358.785648574052;5330.173823057432;5332.253484512172;5338.06583847827;5338.225957236198;5358.089124848785;4382.539833317488;5316.9734151329385;5412.269343638598;5456.290682211326;5460.863478413032;5223.37319521656;5292.708901010443;5296.602754793406;5329.493698098674;5333.333333333333;5734.369219385864;5767.423257674241;5833.183354330397;5845.327787304429;5933.2880054393745;6110.680268050674;6137.407629318945;6161.661108639023;6199.535034872402;6268.216472940882;4783.378160839069;5302.946970530284;6298.0552917062805;6326.272478664966;6343.270523889765;5764.682942438122;5949.048152295655;5974.253218347707;5979.730912108966;5996.910046349309;6352.110704501373;6438.517111216677;6442.774502892376;6471.255805582324;6511.446527045159;6234.750609975641;6238.1890354254565;6241.313455519889;6253.624782512973;6281.937180628135;6172.537059720539;6243.37566243372;6337.809865704036;6347.428731414195;6356.269029061625;6151.231096112986;6246.406591373812;6248.625082495065;6252.656103914328;6268.811935641938;6169.383061693885;6201.410865967726;6221.5333849960525;6223.595466294676;6226.750929962841;4841.009482595302;4948.331975183304;4969.403671559436;4969.627277954168;5018.04657115147;4453.755043472575;4476.171908196994;4510.143072816154;4513.255312873404;4563.748993805372;5895.263092113486;5921.970390148022;5969.880602387928;5972.761089556456;5984.251968503937;6185.536084793658;6218.471429928416;6239.220097487814;6367.134789344814;6380.872382552323;5851.736671849777;5869.413058694096;5876.41235876409;5878.29460464747;5888.852778680567;5453.291072160594;5478.561715062865;5479.178123281539;5481.945180548143;5490.533304669148;6366;6451.548391612542;6550.967245163743;6552.967235163794;6584.374484423972;6291.874162516816;6296.968515157395;6297.96851015742;6305.842353941188;6343.873122537431;6522.673866306761;6541.803745887631;6565.80302590923;6567.310432404529;6568.671566421755;6143.511877689543;6188.938110618837;6213.65824879636;6272.968635156795;6281.780137695158;6139.109829074849;6245.437910588069;6289.591176573691;6304.495640348853;6339;6088;6230.937690623035;6253.718582663882;6260.624362538262;6266.592671476522;6092.390760923872;6139.308154112039;6164.137020817179;6186.1648677427975;6192;5911.083782013795;6013.879722405615;6026.668533230539;6067.544934130046;6102.9694851525455;5022.347094877642;5940.762369505172;6108.053251745987;6120.17287048654;6285.560010799383;6106.0871643692735;6121.981121731823;6127.6476951528675;6128.908066378919;6157.4458298753325 18:24:21 INFO - PROCESS | 2495 | |1;ext.html;492.7068394305387;499.83755279534137;503.17981689845556;533.962622616417;579.8869220502003;33771.324573508515;38525.99999999996;38531;38592.61407385925;38615.806920965355;39878.401823972665;40485.797571012175;40549.391759123566;40723.98190045243;40732.592674073254;9404.52977351133;9607.183389411874;9620.711378658616;9660.98559651237;9765.12113909748;9545.665901693443;9831.65589204378;9846.55690493926;9898.307118501707;9948.701538953843;9817.90182098177;9844;9855.162311203521;9911.306208565404;9927.156191723676;8635.568221588957;9661.855072173888;9753.609855605802;9843.409395436296;9868.062534059252;9594.376365536218;9709.805803883954;9716.61133554657;9736;9770.462624555646;38297.234055319015;38720;38774.22451550981;38774.612253877385;38795.22409551821;3148.149999500145;3272.672732726732;3299.472084466498;3644.5079914211506;3810.8856734297738;28599.428011439653;28637.14088577346;28817.42365152706;29213.415731685458;29299.121026369245;2724.624378953259;2753.5731961545794;2763.226296636945;2785.610014597958;2866.913992580226;282553;355753;359353.9999999974;359678;360043;51608.74195628998;61693.38306616966;62195;63091;63158.36841631617;56348.43651563514;68296;69913;69962;70179.64910175417;17104.657906842043;17308.307667693403;17343.046132462583;17361.392351267645;17378.39175628872;10863.51114199863;13134.60596182116;13258.668533286553;13281.269530175932;13299.66750831237;19635.31276405319;20253.59492810165;20291.8985405072;20455.488612784502;20473.48816279575;8291.212334828182;8367.623456944451;8415.579221038923;8434.932447618834;8446.155384461505;950.8526178442353;991.8115558043949;995.8207522645994;1021.1575450253571;1056.0653821367991;29129.417411652073;29312.413751724845;29349.706502935125;29380;29559.85220073886;1046.837740150278;1112.3648237091463;1121.220751577662;1128.3455595754442;1195.1275568834799;12751.489940402465;13985.021048526505;15000.549983500512;15139.318730657147;15181.924090379478;610053;626769;627376;630114;631840;58650.70674646599;60612.696936515036;60695.786084278065;60715.39284607186;60967.69516152391;1512.0474101316101;1515.886308526865;1529.9541013769606;1530.1737061986403;1573.5515378117182;28620.713792861803;28811.423771524034;29344.70655293505;29371;29406.55890161692;51925.96148077093;57912;59934.40065599463;60008.699956499935;60040;573153;574119;587302;588020;589023;41882;42612.36081458843;43060.35409468798;43637.78181109074;44117.11765764603;46553.69259685763;46847.76576117234;47073;48206.75896620494;48354.03291934212;37702.62297377101;38616.6257732835;38829;38872.028199295244;39036.609633903296;38538;38555.42166867555;38704.41943370908;38889.805550972065;39376.818695439186;34847.303053939286;37713.05717357088;38116;38835.417468737425;39042;31513.21216969594;31871.21813002891;32199;33506.83246583751;33626;25043.74956250414;25059.749402505742;25157.622635660846;25392.365190870376;25504.234872953843 18:24:21 INFO - PROCESS | 2495 | |2;jquery.html;409.8012463954982;410.5381445873392;414.11999501060245;416.49395983879583;420.75175646368655;10862.402567858764;20260.59478810423;20494.872781997023;21067;21100.57798844024;18583.62832743348;19104.140313685883;20086.29697960568;20943;21049.368518944455;5074.746262686869;5334.91997620037;5529.778808847641;5951.434613711691;5957.582969192158;5156.845294641167;5897.439743224409;5968.104784282349;6008.098785182214;6033.7888173913925;5565.276514053167;5899.351071382144;5905.793297234598;5941.613795103326;6024.427679370453;5622.662640241599;5807.04183809673;5816.534677225833;5868.3838196989345;5878.823635290948;5861;5866.061430171152;5891.381404952461;5905.8818823623715;5913.704314784287;11409.600663976804;13342.266175360352;13395.397207125703;13502.932485337511;13642;2093.204582258747;2152.440365504964;2273.329367836492;2416.0698131219433;2650.191691534081;9532.618695252182;9863.964283750174;9899.703008909744;10031.097201251923;10172;1754.7455618935182;1768.4606195110487;1799.4691565988064;1812.3656720147974;1878.6806242938733;99978.00021999833;101334;101574;116282;117403.99999999914;10622.415767132727;10871.619493317696;11863.703407414883;11920.701982450508;11980.460879260454;15355.846441535665;15382.538523844303;15407.383704651913;15409.460668876536;15417.845821541865;8974.506402147814;8991.095702491733;9008.279337652972;9097.636094556276;9130.81738365229;9060.456372617664;9073.31950103745;9117.817643647222;9149.77125571866;9171.678991235274;4716;4729.456112547033;4734.171519983989;4761;4807.735574543433;1654.8014238291482;1979.5941831924422;2019.2225992992803;2025.584755125196;2070.5858828234414;833.8040560468285;893.1425831202118;927.800522887569;928.0580211085753;949.126803340928;19040.33358832434;19890.801091989186;20087.698684519743;20144.294949676692;20282;869.599984007351;935.7847695030181;938.4697645830084;965.4110992294694;982.8820541535061;9160.900691916879;10426.5829366826;10701.78596428067;10746.83879741789;10811.783764324828;566435;621405;629528;634264;643065;94947;104957.95042049482;119167;122989;127973;902.3937391443242;1039.6465201831413;1044.4203467075845;1060.4432672846663;1060.6817954613584;13972.301384930915;17516;17805.910970445064;17913.820861791737;18048.90975545114;53814.46185538094;54000.45999539954;54798.082067127456;55601.44398556124;56535;61950.69024654848;79971.60014200125;80663;81118.59440702759;81177;8175.959120204361;8312.625931832961;8343.040550336545;8407.95796021016;8547.059823419544;9053;9273.397229180082;12765.425555849823;12972.35138243103;13207.801882971571;10753.73115672083;10761.569537218424;10836.783264334827;10965;11056.944715276371;9034.45793252407;10955.452227388758;10966.835497467704;11163.888361116286;11244.26912250701;8945.910540894507;10809.405482698527;11047.281926674741;11246.381449020055;11485.253458525169;7114.324139206767;8180.9590952044855;8250.504969701837;8401.621927013177;8487.575621219037;8087;10042.949785251027;10826.242163048511;11152.442377881236;11156.888431115585 18:24:21 INFO - PROCESS | 2495 | |3;mootools.html;1441.911356925521;1584.6276125110596;1805.8194180581943;1891.5649400637863;1958.7453631027963;7844.607769611511;8023.27790498855;8622.223999840013;8856.601452934625;8878.200961913426;7372.483926125174;8569.485830850137;8717.346199035082;8720.738377848658;8725.083866194038;2453.227233421471;2482.1064416809936;2482.6772519572432;2488.639147323646;2496.2012156110054;2446.3272600034957;2494.463690306584;2505.1232068775876;2508.611165269386;2516.446381796002;2464.950700985979;2481.8510889346608;2512.52262070207;2530.3547595363157;2530.519201351737;2480.7891329237036;2494.7131079925857;2497.5389397202657;2509.6988361396566;2509.82431229814;2471.221565206954;2506.3358210074343;2507.0974449198457;2518.2948774343213;2526.2673824590847;8927.37508374414;8973.41672791273;8993.955030224806;8997.820043599157;9044.638214471479;5293.841184764463;5459.535939445152;5484.2596249506205;5485.040117979338;5513.145462453326;3113.34619729857;3147.3075923297065;3573.910652233689;3606.4229723244157;3643.0710168906758;1032.1691038713832;1042.681981995491;1048.0724558765548;1055.4195192644015;1057.9133400894823;10416.843747343795;11960.940195298968;11979.820302695467;12038.759224815542;12090.214136081127;4758.286257061469;4782.713037217778;4829.106615276185;4832.34763306949;4907.460179380265;10194.592216311265;10195.388276703421;10216.948915255376;10223.642172523925;10337.22470814692;4945.738836596644;5314.707691077027;5478.753456094485;5504.862378440491;5522.530584900329;4407.206702793464;4812.278158276286;4947.2579113133315;4973.030259099441;4984.501549844987;3465.2722928185362;3983.183447393277;4009.1781184857036;4064.918701625951;4129.15352352767;1371.9794203086963;1390.791381292814;1394.4770710983382;1399.2164387942785;1402.1236727045596;818.9303909167668;902.2240872849344;909.7385871650208;914.0630853375211;928.2620316847987;5619.606627536034;5653.378128405872;5799.159121927381;5801.767929282866;5879.118132280107;937.7514958535993;950.7242899559051;965.2181732796457;985.2905907746411;1005.5073014222978;3547.113221694576;3771.698264138862;3810.866619668354;3832.5400951885954;3838.673712734393;10444.529996150191;10707.464626768631;10767.730806729738;10841.945790270998;10846.349219046882;10682.465876706288;10757.30077544957;10774.138068954464;10807.35155890649;10850.511726972147;8357.95821020891;8374.120717324593;8487.448315859448;8498.490090594583;8509.914900850912;5451.509364157245;5498.670079795225;5530.778768849201;5537.501624853782;5591.189277554813;9424.670136545188;9661.71014869555;9773.267004974514;9777.120059194707;9867.259955503223;8123.75628731139;9316.860247096163;9647.228221742384;9808.362456440309;9891.950540247253;6861.176658800976;6890.069840571659;7283.344498995116;7442.404607631484;7599.544027358376;7893.407994400328;8279.875801862856;8341.332693384393;8354.248117669438;8367.288780453486;7233.855322893618;7399.778006659809;7419.220981796833;7432;7563.054618172729;7244.565326080452;7245.347918687344;7291.890621640574;7340.853182936205;7381.335679788845;6483.578567393104;6504.869902601827;6519.5436319457185;6545;6548.639824809491;4710.481846996896;4727.881802954954;4741.810327586858;4833.05755377698;4851.75741212945;4749.738764367993;4777.546133117348;4787.257975013878;4811.855644330676;4882.487338829372 18:24:21 INFO - PROCESS | 2495 | |4;prototype.html;262.41481496263333;327.7410845432109;331.7644472424578;335.01338557946855;337.7466899825131;6594.637294948776;6666.4333531649745;6675.966620166904;6692.498062645297;6716.328367163282;6800.455963522919;6804.523683342168;6821.829454263646;6824.454043676506;6849.4862885283555;5000.899982000358;5004.599632029429;5010.223415370623;5020.121478741224;5032.672876263049;4318.287482565373;5019.774110165033;5027.597792176635;5040.7731652075745;5045.318881950945;4009.9198016039663;4066.5323487798837;4184.0376713355945;4960.2559616057515;4972.87567810804;3610.6028336882923;3923.4311024901517;4285.228658841407;4634.351190833252;4971.950280497185;3361.8823341182942;3372.274960883422;4086.2849001424634;4089.7137200395982;4108.609682080198;6355.616643758591;6441.291457939623;6475.74097036118;6487.416132548046;6506.54454188207;4680.274557443568;5761.251037365158;5794.101914203306;5803.390643982406;5842.123681447774;7124.9693837256655;7166.60583667898;7195.028671129435;7200.495965282433;7216.7834964951135;3497.965020349815;4629.791659375336;4636.513166117543;4637.327587499827;4649.163150632885;7298.56208627484;7568.659410326549;7584.810379740496;7585.582792946386;7599.772006839748;4573.63410927125;4629.953700463019;4644.2801365788355;4667.439907211156;4676.579107880307;4727.621790256771;4738.431388233434;4739.241721324572;4739.431268247763;4742.905141897143;5642.633228840113;5708.4862362387585;5730.08318669011;5764.250647415893;5766.5675074368755;6978.154736454799;7139.143302803593;7238.963805180941;7248.202697703248;7298.2701729826595;1632.755086736999;1643.958901027484;1663.2847875413447;1663.5591568234427;1669.182100770638;1030.886642426178;1041.9270651054505;1062.2351906627216;1062.628080171949;1066.0938202527943;6499.610023398611;6557.377049180321;6710.892702703999;6800.183977922681;6822.590644561342;7584.241575842482;7584.469087163846;7584.886226706604;7617.390608751398;7632.160462349138;6141.477974372139;6215.34738852414;6777.661116944231;6789.1852977643;6833.931660683429;3208.951865722016;3975.423563583322;3987.362022076512;3994.7403418777685;4002.599090318365;7555.13115991657;7935.9206407936335;7972.561509117054;8029.156938521372;8051.154628764012;7761.456698031084;7796.5711885845685;7798.610069496503;7801.336886364723;7815.765527034198;6467.353264673589;7950.602469876483;7968.203179682101;7986.161453047346;8012.759617211493;7209.927900721135;7504.362129219077;7514.286142816423;7568.053993250844;7598.620068996639;7346.191918888811;7387.852242955219;7398.556086634819;7424.6287685614425;7439.9256007439235;7936.722214722688;7955;7956.164602716632;7989.362180752815;8031.3173380261;4680.368150299666;4717.622590192707;4719.3628860103445;4728.645351598575;4736.834210802605;6084.90872636902;7166.390856777232;7416.9258307418395;7468.4398670101045;7481.962590187014;7168.319009694071;7201.603911784902;7206.351428371472;7215.386692131017;7226.458015648742;7204.567725936251;7214.494985350975;7219.205887352282;7223.783286501413;7237.782866514013;5531.391546929754;7024.824379390556;7032.050673159058;7054.788356349318;7078.221395646372;6214.875702486015;6949.548279361826;7229.819254518679;7248.456365772483;7255.129384473897;6862;7003.984422258642;7116.039334689957;7118.25258347866;7188.137423509213 18:24:21 INFO - PROCESS | 2495 | |5;yui.html;691.1291772366817;719.722906680928;728.4281838756577;733.8495608400278;745.5787480073755;14385.424583016667;14895.329710163041;15128.848711512896;15158.393664253445;15196.012259203144;11533.538658453652;13866.168029918212;13876.16742995421;13898.791518127236;13915.93042034791;704.2112833626346;890.0965519997208;925.9907400926005;926.5969303353029;928.0951072704127;910.4218821048615;919.4115765909823;923.1091996223643;924.2882980105314;925.6482536636069;960.2270172511161;971.3443425687668;974.4153507895242;976.6191185437685;980.9852852207187;940.8118376324762;961.6778379242927;965.5124162298065;967.7193613851974;970.0154343341515;703.5145749432875;704.1374316462344;723.3056265984661;723.3309189000154;725.9201487836334;15215.08709477435;15226.010309329859;15230.619234519116;15261.847381526153;15402;12543.93728031354;12606.180598261084;12799.232046077264;12818.359082045954;12911.160774549624;9273.072692730673;9682.903170968271;9921;9944.95027524865;10149.441780702058;3456.7061799747053;3498.702610278129;3514.2620049789575;3564.750467467278;3703.7222208334215;75402.86895696569;75737.86393204106;75899.24100758976;75999;76064.23935760628;8088.907997420273;9232.399894006869;9301.953490232505;9414.905850941403;9442.38624489406;16888.662226755398;20519.281825136048;20752;20995.950202489814;21072.68390974106;7544.547327160388;7677.424193185533;7681.692732290758;7746.883796743054;7862.449628525948;4113.300738874398;4132.834686612502;4149.688773342012;4205.600467955539;4210.073783767566;3540.8760693376125;3590.1563132663805;3591.6408359163875;3597.2625611749527;3603.621619729943;557.183725841644;640.1102467570081;644.0886146103212;649.0945131541473;668.7191379620474;580.216707444946;593.0688818554828;596.1862058290402;611.5016261746662;615.6613862375675;7731.304182623591;7875.409344299086;7978.122406535276;8101.6354264058255;8151.551664658382;539.9919001214985;585.8440937350591;595.3778301674673;604.8760004199129;611.1291409741101;3584.211473475831;3662.8168591570316;3669.8532058717888;3847.4228865669816;3882.2429626222615;84438;85932.14067859367;92735;95680;96220;72926.27073729302;73461;74974.62512687402;75376.62311688406;75584.62207689036;59554;60258;60397.094043588506;60750.39249607536;61108.69445652743;7082.468814838805;7454.9935758674055;8200.589970501333;8485.830283394422;8507.659693612186;35866.64133358633;41167.382489263284;41496.340146393806;46357;46520.83697907579;41738.373924391766;47636.5236347646;57382.45669981909;64837;65051.34948650453;10642.148628109888;10682.73293167677;10759.623413180501;10957.232993690364;10990;11824.23142495735;11837.881621183678;11979.700507487383;12009.939950300193;12343;10919.454027298445;11149.10807135411;11288.492017859051;11470.197086203885;11902.821457677968;10309.381437113798;11203.887961120285;11266;11406.771864562828;11577.189596728485;9974.301798874008;10776.67669969902;10848.891511085103;11083.944580277048;11350.772984540097;9724.978877218073;9725.513724313898;9972.351797133164;10125.79748405013;10333.793324133625;9805.509724513888;9812.313138080266;9826.606935722777;9868.210543156674;9886.110250077527 18:24:21 INFO - PROCESS | 2495 | __end_tp_report 18:24:21 INFO - PROCESS | 2495 | __start_cc_report 18:24:21 INFO - PROCESS | 2495 | _x_x_mozilla_cycle_collect,420 18:24:21 INFO - PROCESS | 2495 | __end_cc_report 18:24:21 INFO - PROCESS | 2495 | __startTimestamp1462584261982__endTimestamp 18:24:21 INFO - PROCESS | 2495 | 18:24:21 INFO - PROCESS | 2495 | ------- Summary: start ------- 18:24:21 INFO - PROCESS | 2495 | Number of tests: 6 18:24:21 INFO - PROCESS | 2495 | 18:24:21 INFO - PROCESS | 2495 | [#0] dojo.html Cycles:165 Average:5811.67 Median:6067.54 stddev:651.22 (10.7%) stddev-sans-first:626.43 18:24:21 INFO - PROCESS | 2495 | Values: 3455.2 3490.2 3513.8 3543.2 3550.2 5539.1 5727.0 5769.2 5771.3 5823.7 4799.9 5587.9 5804.7 5848.6 5886.2 5278.0 5293.9 5348.2 5385.0 5388.9 5204.4 5330.7 5343.1 5350.8 5358.8 5330.2 5332.3 5338.1 5338.2 5358.1 4382.5 5317.0 5412.3 5456.3 5460.9 5223.4 5292.7 5296.6 5329.5 5333.3 5734.4 5767.4 5833.2 5845.3 5933.3 6110.7 6137.4 6161.7 6199.5 6268.2 4783.4 5302.9 6298.1 6326.3 6343.3 5764.7 5949.0 5974.3 5979.7 5996.9 6352.1 6438.5 6442.8 6471.3 6511.4 6234.8 6238.2 6241.3 6253.6 6281.9 6172.5 6243.4 6337.8 6347.4 6356.3 6151.2 6246.4 6248.6 6252.7 6268.8 6169.4 6201.4 6221.5 6223.6 6226.8 4841.0 4948.3 4969.4 4969.6 5018.0 4453.8 4476.2 4510.1 4513.3 4563.7 5895.3 5922.0 5969.9 5972.8 5984.3 6185.5 6218.5 6239.2 6367.1 6380.9 5851.7 5869.4 5876.4 5878.3 5888.9 5453.3 5478.6 5479.2 5481.9 5490.5 6366.0 6451.5 6551.0 6553.0 6584.4 6291.9 6297.0 6298.0 6305.8 6343.9 6522.7 6541.8 6565.8 6567.3 6568.7 6143.5 6188.9 6213.7 6273.0 6281.8 6139.1 6245.4 6289.6 6304.5 6339.0 6088.0 6230.9 6253.7 6260.6 6266.6 6092.4 6139.3 6164.1 6186.2 6192.0 5911.1 6013.9 6026.7 6067.5 6103.0 5022.3 5940.8 6108.1 6120.2 6285.6 6106.1 6122.0 6127.6 6128.9 6157.4 18:24:21 INFO - PROCESS | 2495 | 18:24:21 INFO - PROCESS | 2495 | [#1] ext.html Cycles:180 Average:66559.81 Median:38832.21 stddev:142178.80 (366.1%) stddev-sans-first:142491.12 18:24:22 INFO - PROCESS | 2495 | Values: 492.7 499.8 503.2 534.0 579.9 33771.3 38526.0 38531.0 38592.6 38615.8 39878.4 40485.8 40549.4 40724.0 40732.6 9404.5 9607.2 9620.7 9661.0 9765.1 9545.7 9831.7 9846.6 9898.3 9948.7 9817.9 9844.0 9855.2 9911.3 9927.2 8635.6 9661.9 9753.6 9843.4 9868.1 9594.4 9709.8 9716.6 9736.0 9770.5 38297.2 38720.0 38774.2 38774.6 38795.2 3148.1 3272.7 3299.5 3644.5 3810.9 28599.4 28637.1 28817.4 29213.4 29299.1 2724.6 2753.6 2763.2 2785.6 2866.9 282553.0 355753.0 359354.0 359678.0 360043.0 51608.7 61693.4 62195.0 63091.0 63158.4 56348.4 68296.0 69913.0 69962.0 70179.6 17104.7 17308.3 17343.0 17361.4 17378.4 10863.5 13134.6 13258.7 13281.3 13299.7 19635.3 20253.6 20291.9 20455.5 20473.5 8291.2 8367.6 8415.6 8434.9 8446.2 950.9 991.8 995.8 1021.2 1056.1 29129.4 29312.4 29349.7 29380.0 29559.9 1046.8 1112.4 1121.2 1128.3 1195.1 12751.5 13985.0 15000.5 15139.3 15181.9 610053.0 626769.0 627376.0 630114.0 631840.0 58650.7 60612.7 60695.8 60715.4 60967.7 1512.0 1515.9 1530.0 1530.2 1573.6 28620.7 28811.4 29344.7 29371.0 29406.6 51926.0 57912.0 59934.4 60008.7 60040.0 573153.0 574119.0 587302.0 588020.0 589023.0 41882.0 42612.4 43060.4 43637.8 44117.1 46553.7 46847.8 47073.0 48206.8 48354.0 37702.6 38616.6 38829.0 38872.0 39036.6 38538.0 38555.4 38704.4 38889.8 39376.8 34847.3 37713.1 38116.0 38835.4 39042.0 31513.2 31871.2 32199.0 33506.8 33626.0 25043.7 25059.7 25157.6 25392.4 25504.2 18:24:22 INFO - PROCESS | 2495 | 18:24:22 INFO - PROCESS | 2495 | [#2] jquery.html Cycles:180 Average:34433.60 Median:415.31 stddev:102777.59 (24747.4%) stddev-sans-first:103034.16 18:24:22 INFO - PROCESS | 2495 | Values: 409.8 410.5 414.1 416.5 420.8 10862.4 20260.6 20494.9 21067.0 21100.6 18583.6 19104.1 20086.3 20943.0 21049.4 5074.7 5334.9 5529.8 5951.4 5957.6 5156.8 5897.4 5968.1 6008.1 6033.8 5565.3 5899.4 5905.8 5941.6 6024.4 5622.7 5807.0 5816.5 5868.4 5878.8 5861.0 5866.1 5891.4 5905.9 5913.7 11409.6 13342.3 13395.4 13502.9 13642.0 2093.2 2152.4 2273.3 2416.1 2650.2 9532.6 9864.0 9899.7 10031.1 10172.0 1754.7 1768.5 1799.5 1812.4 1878.7 99978.0 101334.0 101574.0 116282.0 117404.0 10622.4 10871.6 11863.7 11920.7 11980.5 15355.8 15382.5 15407.4 15409.5 15417.8 8974.5 8991.1 9008.3 9097.6 9130.8 9060.5 9073.3 9117.8 9149.8 9171.7 4716.0 4729.5 4734.2 4761.0 4807.7 1654.8 1979.6 2019.2 2025.6 2070.6 833.8 893.1 927.8 928.1 949.1 19040.3 19890.8 20087.7 20144.3 20282.0 869.6 935.8 938.5 965.4 982.9 9160.9 10426.6 10701.8 10746.8 10811.8 566435.0 621405.0 629528.0 634264.0 643065.0 94947.0 104958.0 119167.0 122989.0 127973.0 902.4 1039.6 1044.4 1060.4 1060.7 13972.3 17516.0 17805.9 17913.8 18048.9 53814.5 54000.5 54798.1 55601.4 56535.0 61950.7 79971.6 80663.0 81118.6 81177.0 8176.0 8312.6 8343.0 8408.0 8547.1 9053.0 9273.4 12765.4 12972.4 13207.8 10753.7 10761.6 10836.8 10965.0 11056.9 9034.5 10955.5 10966.8 11163.9 11244.3 8945.9 10809.4 11047.3 11246.4 11485.3 7114.3 8181.0 8250.5 8401.6 8487.6 8087.0 10042.9 10826.2 11152.4 11156.9 18:24:22 INFO - PROCESS | 2495 | 18:24:22 INFO - PROCESS | 2495 | [#3] mootools.html Cycles:180 Average:5677.83 Median:4867.12 stddev:3149.37 (64.7%) stddev-sans-first:3142.11 18:24:22 INFO - PROCESS | 2495 | Values: 1441.9 1584.6 1805.8 1891.6 1958.7 7844.6 8023.3 8622.2 8856.6 8878.2 7372.5 8569.5 8717.3 8720.7 8725.1 2453.2 2482.1 2482.7 2488.6 2496.2 2446.3 2494.5 2505.1 2508.6 2516.4 2465.0 2481.9 2512.5 2530.4 2530.5 2480.8 2494.7 2497.5 2509.7 2509.8 2471.2 2506.3 2507.1 2518.3 2526.3 8927.4 8973.4 8994.0 8997.8 9044.6 5293.8 5459.5 5484.3 5485.0 5513.1 3113.3 3147.3 3573.9 3606.4 3643.1 1032.2 1042.7 1048.1 1055.4 1057.9 10416.8 11960.9 11979.8 12038.8 12090.2 4758.3 4782.7 4829.1 4832.3 4907.5 10194.6 10195.4 10216.9 10223.6 10337.2 4945.7 5314.7 5478.8 5504.9 5522.5 4407.2 4812.3 4947.3 4973.0 4984.5 3465.3 3983.2 4009.2 4064.9 4129.2 1372.0 1390.8 1394.5 1399.2 1402.1 818.9 902.2 909.7 914.1 928.3 5619.6 5653.4 5799.2 5801.8 5879.1 937.8 950.7 965.2 985.3 1005.5 3547.1 3771.7 3810.9 3832.5 3838.7 10444.5 10707.5 10767.7 10841.9 10846.3 10682.5 10757.3 10774.1 10807.4 10850.5 8358.0 8374.1 8487.4 8498.5 8509.9 5451.5 5498.7 5530.8 5537.5 5591.2 9424.7 9661.7 9773.3 9777.1 9867.3 8123.8 9316.9 9647.2 9808.4 9892.0 6861.2 6890.1 7283.3 7442.4 7599.5 7893.4 8279.9 8341.3 8354.2 8367.3 7233.9 7399.8 7419.2 7432.0 7563.1 7244.6 7245.3 7291.9 7340.9 7381.3 6483.6 6504.9 6519.5 6545.0 6548.6 4710.5 4727.9 4741.8 4833.1 4851.8 4749.7 4777.5 4787.3 4811.9 4882.5 18:24:22 INFO - PROCESS | 2495 | 18:24:22 INFO - PROCESS | 2495 | [#4] prototype.html Cycles:180 Average:5842.12 Median:6684.23 stddev:1953.15 (29.2%) stddev-sans-first:1913.21 18:24:22 INFO - PROCESS | 2495 | Values: 262.4 327.7 331.8 335.0 337.7 6594.6 6666.4 6676.0 6692.5 6716.3 6800.5 6804.5 6821.8 6824.5 6849.5 5000.9 5004.6 5010.2 5020.1 5032.7 4318.3 5019.8 5027.6 5040.8 5045.3 4009.9 4066.5 4184.0 4960.3 4972.9 3610.6 3923.4 4285.2 4634.4 4972.0 3361.9 3372.3 4086.3 4089.7 4108.6 6355.6 6441.3 6475.7 6487.4 6506.5 4680.3 5761.3 5794.1 5803.4 5842.1 7125.0 7166.6 7195.0 7200.5 7216.8 3498.0 4629.8 4636.5 4637.3 4649.2 7298.6 7568.7 7584.8 7585.6 7599.8 4573.6 4630.0 4644.3 4667.4 4676.6 4727.6 4738.4 4739.2 4739.4 4742.9 5642.6 5708.5 5730.1 5764.3 5766.6 6978.2 7139.1 7239.0 7248.2 7298.3 1632.8 1644.0 1663.3 1663.6 1669.2 1030.9 1041.9 1062.2 1062.6 1066.1 6499.6 6557.4 6710.9 6800.2 6822.6 7584.2 7584.5 7584.9 7617.4 7632.2 6141.5 6215.3 6777.7 6789.2 6833.9 3209.0 3975.4 3987.4 3994.7 4002.6 7555.1 7935.9 7972.6 8029.2 8051.2 7761.5 7796.6 7798.6 7801.3 7815.8 6467.4 7950.6 7968.2 7986.2 8012.8 7209.9 7504.4 7514.3 7568.1 7598.6 7346.2 7387.9 7398.6 7424.6 7439.9 7936.7 7955.0 7956.2 7989.4 8031.3 4680.4 4717.6 4719.4 4728.6 4736.8 6084.9 7166.4 7416.9 7468.4 7482.0 7168.3 7201.6 7206.4 7215.4 7226.5 7204.6 7214.5 7219.2 7223.8 7237.8 5531.4 7024.8 7032.1 7054.8 7078.2 6214.9 6949.5 7229.8 7248.5 7255.1 6862.0 7004.0 7116.0 7118.3 7188.1 18:24:22 INFO - PROCESS | 2495 | 18:24:22 INFO - PROCESS | 2495 | [#5] yui.html Cycles:180 Average:17116.00 Median:30677.63 stddev:23703.64 (77.3%) stddev-sans-first:23738.05 18:24:22 INFO - PROCESS | 2495 | Values: 691.1 719.7 728.4 733.8 745.6 14385.4 14895.3 15128.8 15158.4 15196.0 11533.5 13866.2 13876.2 13898.8 13915.9 704.2 890.1 926.0 926.6 928.1 910.4 919.4 923.1 924.3 925.6 960.2 971.3 974.4 976.6 981.0 940.8 961.7 965.5 967.7 970.0 703.5 704.1 723.3 723.3 725.9 15215.1 15226.0 15230.6 15261.8 15402.0 12543.9 12606.2 12799.2 12818.4 12911.2 9273.1 9682.9 9921.0 9945.0 10149.4 3456.7 3498.7 3514.3 3564.8 3703.7 75402.9 75737.9 75899.2 75999.0 76064.2 8088.9 9232.4 9302.0 9414.9 9442.4 16888.7 20519.3 20752.0 20996.0 21072.7 7544.5 7677.4 7681.7 7746.9 7862.4 4113.3 4132.8 4149.7 4205.6 4210.1 3540.9 3590.2 3591.6 3597.3 3603.6 557.2 640.1 644.1 649.1 668.7 580.2 593.1 596.2 611.5 615.7 7731.3 7875.4 7978.1 8101.6 8151.6 540.0 585.8 595.4 604.9 611.1 3584.2 3662.8 3669.9 3847.4 3882.2 84438.0 85932.1 92735.0 95680.0 96220.0 72926.3 73461.0 74974.6 75376.6 75584.6 59554.0 60258.0 60397.1 60750.4 61108.7 7082.5 7455.0 8200.6 8485.8 8507.7 35866.6 41167.4 41496.3 46357.0 46520.8 41738.4 47636.5 57382.5 64837.0 65051.3 10642.1 10682.7 10759.6 10957.2 10990.0 11824.2 11837.9 11979.7 12009.9 12343.0 10919.5 11149.1 11288.5 11470.2 11902.8 10309.4 11203.9 11266.0 11406.8 11577.2 9974.3 10776.7 10848.9 11083.9 11350.8 9725.0 9725.5 9972.4 10125.8 10333.8 9805.5 9812.3 9826.6 9868.2 9886.1 18:24:22 INFO - PROCESS | 2495 | -------- Summary: end -------- 18:24:22 INFO - PROCESS | 2495 | 18:24:22 INFO - TEST-INFO | 2495: exit 0 18:24:22 INFO - TEST-OK | dromaeo_css | took 1112067ms 18:24:22 INFO - TEST-START | kraken 18:24:22 INFO - operating with platform_type : mac_ 18:24:22 INFO - Initialising browser for kraken test... 18:24:22 INFO - TEST-INFO | started process 2500 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpQBFSH1/profile http://localhost:54657/getInfo.html) 18:24:25 INFO - PROCESS | 2500 | __metrics Screen width/height:1600/1200 18:24:25 INFO - PROCESS | 2500 | colorDepth:24 18:24:25 INFO - PROCESS | 2500 | Browser inner width/height: 1024/689 18:24:25 INFO - PROCESS | 2500 | __metrics 18:24:25 ERROR - PROCESS | 2500 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 18:24:26 INFO - TEST-INFO | 2500: exit 0 18:24:26 INFO - Browser initialized. 18:24:26 INFO - Running cycle 1/1 for kraken test... 18:24:26 INFO - TEST-INFO | started process 2503 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpQBFSH1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:24:38 INFO - PROCESS | 2503 | RSS: Main: 221536256 18:24:38 INFO - PROCESS | 2503 | 18:24:41 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:54657/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54657/tests/kraken/driver.html?testName=audio-beat-detection) 18:24:42 INFO - PROCESS | 2503 | RSS: Main: 233254912 18:24:42 INFO - PROCESS | 2503 | 18:24:45 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:54657/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54657/tests/kraken/driver.html?testName=audio-dft) 18:24:45 INFO - PROCESS | 2503 | RSS: Main: 242130944 18:24:45 INFO - PROCESS | 2503 | 18:24:49 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:54657/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54657/tests/kraken/driver.html?testName=audio-fft) 18:24:49 INFO - PROCESS | 2503 | RSS: Main: 245768192 18:24:49 INFO - PROCESS | 2503 | 18:24:51 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:54657/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54657/tests/kraken/driver.html?testName=audio-oscillator) 18:24:52 INFO - PROCESS | 2503 | RSS: Main: 247865344 18:24:52 INFO - PROCESS | 2503 | 18:24:54 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:54657/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54657/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:24:55 INFO - PROCESS | 2503 | RSS: Main: 248008704 18:24:55 INFO - PROCESS | 2503 | 18:24:58 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:54657/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54657/tests/kraken/driver.html?testName=imaging-darkroom) 18:24:58 INFO - PROCESS | 2503 | RSS: Main: 248463360 18:24:58 INFO - PROCESS | 2503 | 18:25:01 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:54657/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54657/tests/kraken/driver.html?testName=imaging-desaturate) 18:25:02 INFO - PROCESS | 2503 | RSS: Main: 248471552 18:25:02 INFO - PROCESS | 2503 | 18:25:05 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:54657/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54657/tests/kraken/driver.html?testName=json-parse-financial) 18:25:06 INFO - PROCESS | 2503 | RSS: Main: 248565760 18:25:06 INFO - PROCESS | 2503 | 18:25:07 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:54657/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54657/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:25:08 INFO - PROCESS | 2503 | RSS: Main: 247775232 18:25:08 INFO - PROCESS | 2503 | 18:25:10 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:54657/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54657/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:25:10 INFO - PROCESS | 2503 | RSS: Main: 247795712 18:25:10 INFO - PROCESS | 2503 | 18:25:12 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:54657/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54657/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:25:13 INFO - PROCESS | 2503 | RSS: Main: 248041472 18:25:13 INFO - PROCESS | 2503 | 18:25:16 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:54657/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54657/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:25:17 INFO - PROCESS | 2503 | RSS: Main: 248041472 18:25:17 INFO - PROCESS | 2503 | 18:25:19 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:54657/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54657/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:25:20 INFO - PROCESS | 2503 | RSS: Main: 247992320 18:25:20 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:54657/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54657/tests/kraken/driver.html?testName=ai-astar) 18:25:22 INFO - PROCESS | 2503 | RSS: Main: 249626624 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | __start_tp_report 18:25:22 INFO - PROCESS | 2503 | _x_x_mozilla_page_load 18:25:22 INFO - PROCESS | 2503 | _x_x_mozilla_page_load_details 18:25:22 INFO - PROCESS | 2503 | |i|pagename|runs| 18:25:22 INFO - PROCESS | 2503 | |0;ai-astar;115;102;161;173;128;130;117;116;131;98 18:25:22 INFO - PROCESS | 2503 | |1;audio-beat-detection;152;132;142;212;155;173;160;156;190;155 18:25:22 INFO - PROCESS | 2503 | |2;audio-dft;197;200;201;207;193;197;197;194;199;195 18:25:22 INFO - PROCESS | 2503 | |3;audio-fft;71;79;77;169;48;57;54;56;153;48 18:25:22 INFO - PROCESS | 2503 | |4;audio-oscillator;81;86;159;116;87;89;120;104;114;160 18:25:22 INFO - PROCESS | 2503 | |5;imaging-gaussian-blur;88;79;127;131;135;118;88;120;87;84 18:25:22 INFO - PROCESS | 2503 | |6;imaging-darkroom;94;97;93;93;94;94;103;153;153;105 18:25:22 INFO - PROCESS | 2503 | |7;imaging-desaturate;91;94;106;92;85;151;148;92;91;91 18:25:22 INFO - PROCESS | 2503 | |8;json-parse-financial;60;67;66;65;67;64;65;74;64;61 18:25:22 INFO - PROCESS | 2503 | |9;json-stringify-tinderbox;65;69;69;74;72;74;69;72;74;62 18:25:22 INFO - PROCESS | 2503 | |10;stanford-crypto-aes;96;106;97;81;83;82;84;81;85;86 18:25:22 INFO - PROCESS | 2503 | |11;stanford-crypto-ccm;130;134;116;155;148;145;132;150;182;167 18:25:22 INFO - PROCESS | 2503 | |12;stanford-crypto-pbkdf2;169;167;170;169;165;221;172;172;172;167 18:25:22 INFO - PROCESS | 2503 | |13;stanford-crypto-sha256-iterative;71;70;70;69;65;69;66;71;69;66 18:25:22 INFO - PROCESS | 2503 | __end_tp_report 18:25:22 INFO - PROCESS | 2503 | __start_cc_report 18:25:22 INFO - PROCESS | 2503 | _x_x_mozilla_cycle_collect,1089 18:25:22 INFO - PROCESS | 2503 | __end_cc_report 18:25:22 INFO - PROCESS | 2503 | __startTimestamp1462584322570__endTimestamp 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | ------- Summary: start ------- 18:25:22 INFO - PROCESS | 2503 | Number of tests: 14 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | [#0] ai-astar Cycles:10 Average:127.10 Median:130.50 stddev:23.87 (18.3%) stddev-sans-first:24.92 18:25:22 INFO - PROCESS | 2503 | Values: 115.0 102.0 161.0 173.0 128.0 130.0 117.0 116.0 131.0 98.0 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | [#1] audio-beat-detection Cycles:10 Average:162.70 Median:158.00 stddev:23.42 (14.8%) stddev-sans-first:24.52 18:25:22 INFO - PROCESS | 2503 | Values: 152.0 132.0 142.0 212.0 155.0 173.0 160.0 156.0 190.0 155.0 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | [#2] audio-dft Cycles:10 Average:198.00 Median:198.00 stddev:4.06 (2.0%) stddev-sans-first:4.28 18:25:22 INFO - PROCESS | 2503 | Values: 197.0 200.0 201.0 207.0 193.0 197.0 197.0 194.0 199.0 195.0 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | [#3] audio-fft Cycles:10 Average:81.20 Median:56.50 stddev:43.66 (77.3%) stddev-sans-first:46.15 18:25:22 INFO - PROCESS | 2503 | Values: 71.0 79.0 77.0 169.0 48.0 57.0 54.0 56.0 153.0 48.0 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | [#4] audio-oscillator Cycles:10 Average:111.60 Median:120.50 stddev:28.77 (23.9%) stddev-sans-first:28.31 18:25:22 INFO - PROCESS | 2503 | Values: 81.0 86.0 159.0 116.0 87.0 89.0 120.0 104.0 114.0 160.0 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | [#5] imaging-gaussian-blur Cycles:10 Average:105.70 Median:81.50 stddev:22.28 (27.3%) stddev-sans-first:22.69 18:25:22 INFO - PROCESS | 2503 | Values: 88.0 79.0 127.0 131.0 135.0 118.0 88.0 120.0 87.0 84.0 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | [#6] imaging-darkroom Cycles:10 Average:107.90 Median:93.50 stddev:24.14 (25.8%) stddev-sans-first:25.07 18:25:22 INFO - PROCESS | 2503 | Values: 94.0 97.0 93.0 93.0 94.0 94.0 103.0 153.0 153.0 105.0 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | [#7] imaging-desaturate Cycles:10 Average:104.10 Median:91.00 stddev:24.51 (26.9%) stddev-sans-first:25.53 18:25:22 INFO - PROCESS | 2503 | Values: 91.0 94.0 106.0 92.0 85.0 151.0 148.0 92.0 91.0 91.0 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | [#8] json-parse-financial Cycles:10 Average:65.30 Median:65.50 stddev:3.83 (5.8%) stddev-sans-first:3.55 18:25:22 INFO - PROCESS | 2503 | Values: 60.0 67.0 66.0 65.0 67.0 64.0 65.0 74.0 64.0 61.0 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:72.00 stddev:4.06 (5.6%) stddev-sans-first:3.88 18:25:22 INFO - PROCESS | 2503 | Values: 65.0 69.0 69.0 74.0 72.0 74.0 69.0 72.0 74.0 62.0 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:84.50 stddev:8.54 (10.1%) stddev-sans-first:8.57 18:25:22 INFO - PROCESS | 2503 | Values: 96.0 106.0 97.0 81.0 83.0 82.0 84.0 81.0 85.0 86.0 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | [#11] stanford-crypto-ccm Cycles:10 Average:145.90 Median:149.00 stddev:19.25 (12.9%) stddev-sans-first:19.54 18:25:22 INFO - PROCESS | 2503 | Values: 130.0 134.0 116.0 155.0 148.0 145.0 132.0 150.0 182.0 167.0 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.40 Median:171.00 stddev:16.55 (9.7%) stddev-sans-first:17.44 18:25:22 INFO - PROCESS | 2503 | Values: 169.0 167.0 170.0 169.0 165.0 221.0 172.0 172.0 172.0 167.0 18:25:22 INFO - PROCESS | 2503 | 18:25:22 INFO - PROCESS | 2503 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.50 stddev:2.17 (3.1%) stddev-sans-first:2.12 18:25:22 INFO - PROCESS | 2503 | Values: 71.0 70.0 70.0 69.0 65.0 69.0 66.0 71.0 69.0 66.0 18:25:22 INFO - PROCESS | 2503 | -------- Summary: end -------- 18:25:22 INFO - PROCESS | 2503 | 18:25:23 INFO - TEST-INFO | 2503: exit 0 18:25:23 INFO - TEST-OK | kraken | took 60848ms 18:25:23 INFO - SUITE-END | took 1172s 18:25:23 INFO - Completed test suite (00:19:33) 18:25:23 INFO - javascript benchmark 18:25:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3455.2398472336085, 3490.232148927235, 3513.8243087845603, 3543.202779374641, 3550.236699109691, 5539.113741801312, 5727.026405511063, 5769.221155144054, 5771.278590176234, 5823.650580965145, 4799.856004319859, 5587.938291724569, 5804.709764511768, 5848.561357898154, 5886.205362276091, 5278.049951008817, 5293.8941221175555, 5348.224507446418, 5384.973075134619, 5388.91916621251, 5204.375474943012, 5330.680159190441, 5343.145096784515, 5350.8127215547465, 5358.785648574052, 5330.173823057432, 5332.253484512172, 5338.06583847827, 5338.225957236198, 5358.089124848785, 4382.539833317488, 5316.9734151329385, 5412.269343638598, 5456.290682211326, 5460.863478413032, 5223.37319521656, 5292.708901010443, 5296.602754793406, 5329.493698098674, 5333.333333333333, 5734.369219385864, 5767.423257674241, 5833.183354330397, 5845.327787304429, 5933.2880054393745, 6110.680268050674, 6137.407629318945, 6161.661108639023, 6199.535034872402, 6268.216472940882, 4783.378160839069, 5302.946970530284, 6298.0552917062805, 6326.272478664966, 6343.270523889765, 5764.682942438122, 5949.048152295655, 5974.253218347707, 5979.730912108966, 5996.910046349309, 6352.110704501373, 6438.517111216677, 6442.774502892376, 6471.255805582324, 6511.446527045159, 6234.750609975641, 6238.1890354254565, 6241.313455519889, 6253.624782512973, 6281.937180628135, 6172.537059720539, 6243.37566243372, 6337.809865704036, 6347.428731414195, 6356.269029061625, 6151.231096112986, 6246.406591373812, 6248.625082495065, 6252.656103914328, 6268.811935641938, 6169.383061693885, 6201.410865967726, 6221.5333849960525, 6223.595466294676, 6226.750929962841, 4841.009482595302, 4948.331975183304, 4969.403671559436, 4969.627277954168, 5018.04657115147, 4453.755043472575, 4476.171908196994, 4510.143072816154, 4513.255312873404, 4563.748993805372, 5895.263092113486, 5921.970390148022, 5969.880602387928, 5972.761089556456, 5984.251968503937, 6185.536084793658, 6218.471429928416, 6239.220097487814, 6367.134789344814, 6380.872382552323, 5851.736671849777, 5869.413058694096, 5876.41235876409, 5878.29460464747, 5888.852778680567, 5453.291072160594, 5478.561715062865, 5479.178123281539, 5481.945180548143, 5490.533304669148, 6366.0, 6451.548391612542, 6550.967245163743, 6552.967235163794, 6584.374484423972, 6291.874162516816, 6296.968515157395, 6297.96851015742, 6305.842353941188, 6343.873122537431, 6522.673866306761, 6541.803745887631, 6565.80302590923, 6567.310432404529, 6568.671566421755, 6143.511877689543, 6188.938110618837, 6213.65824879636, 6272.968635156795, 6281.780137695158, 6139.109829074849, 6245.437910588069, 6289.591176573691, 6304.495640348853, 6339.0, 6088.0, 6230.937690623035, 6253.718582663882, 6260.624362538262, 6266.592671476522, 6092.390760923872, 6139.308154112039, 6164.137020817179, 6186.1648677427975, 6192.0, 5911.083782013795, 6013.879722405615, 6026.668533230539, 6067.544934130046, 6102.9694851525455, 5022.347094877642, 5940.762369505172, 6108.053251745987, 6120.17287048654, 6285.560010799383, 6106.0871643692735, 6121.981121731823, 6127.6476951528675, 6128.908066378919, 6157.4458298753325], "value": 5772.2932363629625, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.7068394305387, 499.83755279534137, 503.17981689845556, 533.962622616417, 579.8869220502003, 33771.324573508515, 38525.99999999996, 38531.0, 38592.61407385925, 38615.806920965355, 39878.401823972665, 40485.797571012175, 40549.391759123566, 40723.98190045243, 40732.592674073254, 9404.52977351133, 9607.183389411874, 9620.711378658616, 9660.98559651237, 9765.12113909748, 9545.665901693443, 9831.65589204378, 9846.55690493926, 9898.307118501707, 9948.701538953843, 9817.90182098177, 9844.0, 9855.162311203521, 9911.306208565404, 9927.156191723676, 8635.568221588957, 9661.855072173888, 9753.609855605802, 9843.409395436296, 9868.062534059252, 9594.376365536218, 9709.805803883954, 9716.61133554657, 9736.0, 9770.462624555646, 38297.234055319015, 38720.0, 38774.22451550981, 38774.612253877385, 38795.22409551821, 3148.149999500145, 3272.672732726732, 3299.472084466498, 3644.5079914211506, 3810.8856734297738, 28599.428011439653, 28637.14088577346, 28817.42365152706, 29213.415731685458, 29299.121026369245, 2724.624378953259, 2753.5731961545794, 2763.226296636945, 2785.610014597958, 2866.913992580226, 282553.0, 355753.0, 359353.9999999974, 359678.0, 360043.0, 51608.74195628998, 61693.38306616966, 62195.0, 63091.0, 63158.36841631617, 56348.43651563514, 68296.0, 69913.0, 69962.0, 70179.64910175417, 17104.657906842043, 17308.307667693403, 17343.046132462583, 17361.392351267645, 17378.39175628872, 10863.51114199863, 13134.60596182116, 13258.668533286553, 13281.269530175932, 13299.66750831237, 19635.31276405319, 20253.59492810165, 20291.8985405072, 20455.488612784502, 20473.48816279575, 8291.212334828182, 8367.623456944451, 8415.579221038923, 8434.932447618834, 8446.155384461505, 950.8526178442353, 991.8115558043949, 995.8207522645994, 1021.1575450253571, 1056.0653821367991, 29129.417411652073, 29312.413751724845, 29349.706502935125, 29380.0, 29559.85220073886, 1046.837740150278, 1112.3648237091463, 1121.220751577662, 1128.3455595754442, 1195.1275568834799, 12751.489940402465, 13985.021048526505, 15000.549983500512, 15139.318730657147, 15181.924090379478, 610053.0, 626769.0, 627376.0, 630114.0, 631840.0, 58650.70674646599, 60612.696936515036, 60695.786084278065, 60715.39284607186, 60967.69516152391, 1512.0474101316101, 1515.886308526865, 1529.9541013769606, 1530.1737061986403, 1573.5515378117182, 28620.713792861803, 28811.423771524034, 29344.70655293505, 29371.0, 29406.55890161692, 51925.96148077093, 57912.0, 59934.40065599463, 60008.699956499935, 60040.0, 573153.0, 574119.0, 587302.0, 588020.0, 589023.0, 41882.0, 42612.36081458843, 43060.35409468798, 43637.78181109074, 44117.11765764603, 46553.69259685763, 46847.76576117234, 47073.0, 48206.75896620494, 48354.03291934212, 37702.62297377101, 38616.6257732835, 38829.0, 38872.028199295244, 39036.609633903296, 38538.0, 38555.42166867555, 38704.41943370908, 38889.805550972065, 39376.818695439186, 34847.303053939286, 37713.05717357088, 38116.0, 38835.417468737425, 39042.0, 31513.21216969594, 31871.21813002891, 32199.0, 33506.83246583751, 33626.0, 25043.74956250414, 25059.749402505742, 25157.622635660846, 25392.365190870376, 25504.234872953843], "value": 20197.208208122738, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.8012463954982, 410.5381445873392, 414.11999501060245, 416.49395983879583, 420.75175646368655, 10862.402567858764, 20260.59478810423, 20494.872781997023, 21067.0, 21100.57798844024, 18583.62832743348, 19104.140313685883, 20086.29697960568, 20943.0, 21049.368518944455, 5074.746262686869, 5334.91997620037, 5529.778808847641, 5951.434613711691, 5957.582969192158, 5156.845294641167, 5897.439743224409, 5968.104784282349, 6008.098785182214, 6033.7888173913925, 5565.276514053167, 5899.351071382144, 5905.793297234598, 5941.613795103326, 6024.427679370453, 5622.662640241599, 5807.04183809673, 5816.534677225833, 5868.3838196989345, 5878.823635290948, 5861.0, 5866.061430171152, 5891.381404952461, 5905.8818823623715, 5913.704314784287, 11409.600663976804, 13342.266175360352, 13395.397207125703, 13502.932485337511, 13642.0, 2093.204582258747, 2152.440365504964, 2273.329367836492, 2416.0698131219433, 2650.191691534081, 9532.618695252182, 9863.964283750174, 9899.703008909744, 10031.097201251923, 10172.0, 1754.7455618935182, 1768.4606195110487, 1799.4691565988064, 1812.3656720147974, 1878.6806242938733, 99978.00021999833, 101334.0, 101574.0, 116282.0, 117403.99999999914, 10622.415767132727, 10871.619493317696, 11863.703407414883, 11920.701982450508, 11980.460879260454, 15355.846441535665, 15382.538523844303, 15407.383704651913, 15409.460668876536, 15417.845821541865, 8974.506402147814, 8991.095702491733, 9008.279337652972, 9097.636094556276, 9130.81738365229, 9060.456372617664, 9073.31950103745, 9117.817643647222, 9149.77125571866, 9171.678991235274, 4716.0, 4729.456112547033, 4734.171519983989, 4761.0, 4807.735574543433, 1654.8014238291482, 1979.5941831924422, 2019.2225992992803, 2025.584755125196, 2070.5858828234414, 833.8040560468285, 893.1425831202118, 927.800522887569, 928.0580211085753, 949.126803340928, 19040.33358832434, 19890.801091989186, 20087.698684519743, 20144.294949676692, 20282.0, 869.599984007351, 935.7847695030181, 938.4697645830084, 965.4110992294694, 982.8820541535061, 9160.900691916879, 10426.5829366826, 10701.78596428067, 10746.83879741789, 10811.783764324828, 566435.0, 621405.0, 629528.0, 634264.0, 643065.0, 94947.0, 104957.95042049482, 119167.0, 122989.0, 127973.0, 902.3937391443242, 1039.6465201831413, 1044.4203467075845, 1060.4432672846663, 1060.6817954613584, 13972.301384930915, 17516.0, 17805.910970445064, 17913.820861791737, 18048.90975545114, 53814.46185538094, 54000.45999539954, 54798.082067127456, 55601.44398556124, 56535.0, 61950.69024654848, 79971.60014200125, 80663.0, 81118.59440702759, 81177.0, 8175.959120204361, 8312.625931832961, 8343.040550336545, 8407.95796021016, 8547.059823419544, 9053.0, 9273.397229180082, 12765.425555849823, 12972.35138243103, 13207.801882971571, 10753.73115672083, 10761.569537218424, 10836.783264334827, 10965.0, 11056.944715276371, 9034.45793252407, 10955.452227388758, 10966.835497467704, 11163.888361116286, 11244.26912250701, 8945.910540894507, 10809.405482698527, 11047.281926674741, 11246.381449020055, 11485.253458525169, 7114.324139206767, 8180.9590952044855, 8250.504969701837, 8401.621927013177, 8487.575621219037, 8087.0, 10042.949785251027, 10826.242163048511, 11152.442377881236, 11156.888431115585], "value": 9291.4295324361, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.911356925521, 1584.6276125110596, 1805.8194180581943, 1891.5649400637863, 1958.7453631027963, 7844.607769611511, 8023.27790498855, 8622.223999840013, 8856.601452934625, 8878.200961913426, 7372.483926125174, 8569.485830850137, 8717.346199035082, 8720.738377848658, 8725.083866194038, 2453.227233421471, 2482.1064416809936, 2482.6772519572432, 2488.639147323646, 2496.2012156110054, 2446.3272600034957, 2494.463690306584, 2505.1232068775876, 2508.611165269386, 2516.446381796002, 2464.950700985979, 2481.8510889346608, 2512.52262070207, 2530.3547595363157, 2530.519201351737, 2480.7891329237036, 2494.7131079925857, 2497.5389397202657, 2509.6988361396566, 2509.82431229814, 2471.221565206954, 2506.3358210074343, 2507.0974449198457, 2518.2948774343213, 2526.2673824590847, 8927.37508374414, 8973.41672791273, 8993.955030224806, 8997.820043599157, 9044.638214471479, 5293.841184764463, 5459.535939445152, 5484.2596249506205, 5485.040117979338, 5513.145462453326, 3113.34619729857, 3147.3075923297065, 3573.910652233689, 3606.4229723244157, 3643.0710168906758, 1032.1691038713832, 1042.681981995491, 1048.0724558765548, 1055.4195192644015, 1057.9133400894823, 10416.843747343795, 11960.940195298968, 11979.820302695467, 12038.759224815542, 12090.214136081127, 4758.286257061469, 4782.713037217778, 4829.106615276185, 4832.34763306949, 4907.460179380265, 10194.592216311265, 10195.388276703421, 10216.948915255376, 10223.642172523925, 10337.22470814692, 4945.738836596644, 5314.707691077027, 5478.753456094485, 5504.862378440491, 5522.530584900329, 4407.206702793464, 4812.278158276286, 4947.2579113133315, 4973.030259099441, 4984.501549844987, 3465.2722928185362, 3983.183447393277, 4009.1781184857036, 4064.918701625951, 4129.15352352767, 1371.9794203086963, 1390.791381292814, 1394.4770710983382, 1399.2164387942785, 1402.1236727045596, 818.9303909167668, 902.2240872849344, 909.7385871650208, 914.0630853375211, 928.2620316847987, 5619.606627536034, 5653.378128405872, 5799.159121927381, 5801.767929282866, 5879.118132280107, 937.7514958535993, 950.7242899559051, 965.2181732796457, 985.2905907746411, 1005.5073014222978, 3547.113221694576, 3771.698264138862, 3810.866619668354, 3832.5400951885954, 3838.673712734393, 10444.529996150191, 10707.464626768631, 10767.730806729738, 10841.945790270998, 10846.349219046882, 10682.465876706288, 10757.30077544957, 10774.138068954464, 10807.35155890649, 10850.511726972147, 8357.95821020891, 8374.120717324593, 8487.448315859448, 8498.490090594583, 8509.914900850912, 5451.509364157245, 5498.670079795225, 5530.778768849201, 5537.501624853782, 5591.189277554813, 9424.670136545188, 9661.71014869555, 9773.267004974514, 9777.120059194707, 9867.259955503223, 8123.75628731139, 9316.860247096163, 9647.228221742384, 9808.362456440309, 9891.950540247253, 6861.176658800976, 6890.069840571659, 7283.344498995116, 7442.404607631484, 7599.544027358376, 7893.407994400328, 8279.875801862856, 8341.332693384393, 8354.248117669438, 8367.288780453486, 7233.855322893618, 7399.778006659809, 7419.220981796833, 7432.0, 7563.054618172729, 7244.565326080452, 7245.347918687344, 7291.890621640574, 7340.853182936205, 7381.335679788845, 6483.578567393104, 6504.869902601827, 6519.5436319457185, 6545.0, 6548.639824809491, 4710.481846996896, 4727.881802954954, 4741.810327586858, 4833.05755377698, 4851.75741212945, 4749.738764367993, 4777.546133117348, 4787.257975013878, 4811.855644330676, 4882.487338829372], "value": 4605.764788785069, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [262.41481496263333, 327.7410845432109, 331.7644472424578, 335.01338557946855, 337.7466899825131, 6594.637294948776, 6666.4333531649745, 6675.966620166904, 6692.498062645297, 6716.328367163282, 6800.455963522919, 6804.523683342168, 6821.829454263646, 6824.454043676506, 6849.4862885283555, 5000.899982000358, 5004.599632029429, 5010.223415370623, 5020.121478741224, 5032.672876263049, 4318.287482565373, 5019.774110165033, 5027.597792176635, 5040.7731652075745, 5045.318881950945, 4009.9198016039663, 4066.5323487798837, 4184.0376713355945, 4960.2559616057515, 4972.87567810804, 3610.6028336882923, 3923.4311024901517, 4285.228658841407, 4634.351190833252, 4971.950280497185, 3361.8823341182942, 3372.274960883422, 4086.2849001424634, 4089.7137200395982, 4108.609682080198, 6355.616643758591, 6441.291457939623, 6475.74097036118, 6487.416132548046, 6506.54454188207, 4680.274557443568, 5761.251037365158, 5794.101914203306, 5803.390643982406, 5842.123681447774, 7124.9693837256655, 7166.60583667898, 7195.028671129435, 7200.495965282433, 7216.7834964951135, 3497.965020349815, 4629.791659375336, 4636.513166117543, 4637.327587499827, 4649.163150632885, 7298.56208627484, 7568.659410326549, 7584.810379740496, 7585.582792946386, 7599.772006839748, 4573.63410927125, 4629.953700463019, 4644.2801365788355, 4667.439907211156, 4676.579107880307, 4727.621790256771, 4738.431388233434, 4739.241721324572, 4739.431268247763, 4742.905141897143, 5642.633228840113, 5708.4862362387585, 5730.08318669011, 5764.250647415893, 5766.5675074368755, 6978.154736454799, 7139.143302803593, 7238.963805180941, 7248.202697703248, 7298.2701729826595, 1632.755086736999, 1643.958901027484, 1663.2847875413447, 1663.5591568234427, 1669.182100770638, 1030.886642426178, 1041.9270651054505, 1062.2351906627216, 1062.628080171949, 1066.0938202527943, 6499.610023398611, 6557.377049180321, 6710.892702703999, 6800.183977922681, 6822.590644561342, 7584.241575842482, 7584.469087163846, 7584.886226706604, 7617.390608751398, 7632.160462349138, 6141.477974372139, 6215.34738852414, 6777.661116944231, 6789.1852977643, 6833.931660683429, 3208.951865722016, 3975.423563583322, 3987.362022076512, 3994.7403418777685, 4002.599090318365, 7555.13115991657, 7935.9206407936335, 7972.561509117054, 8029.156938521372, 8051.154628764012, 7761.456698031084, 7796.5711885845685, 7798.610069496503, 7801.336886364723, 7815.765527034198, 6467.353264673589, 7950.602469876483, 7968.203179682101, 7986.161453047346, 8012.759617211493, 7209.927900721135, 7504.362129219077, 7514.286142816423, 7568.053993250844, 7598.620068996639, 7346.191918888811, 7387.852242955219, 7398.556086634819, 7424.6287685614425, 7439.9256007439235, 7936.722214722688, 7955.0, 7956.164602716632, 7989.362180752815, 8031.3173380261, 4680.368150299666, 4717.622590192707, 4719.3628860103445, 4728.645351598575, 4736.834210802605, 6084.90872636902, 7166.390856777232, 7416.9258307418395, 7468.4398670101045, 7481.962590187014, 7168.319009694071, 7201.603911784902, 7206.351428371472, 7215.386692131017, 7226.458015648742, 7204.567725936251, 7214.494985350975, 7219.205887352282, 7223.783286501413, 7237.782866514013, 5531.391546929754, 7024.824379390556, 7032.050673159058, 7054.788356349318, 7078.221395646372, 6214.875702486015, 6949.548279361826, 7229.819254518679, 7248.456365772483, 7255.129384473897, 6862.0, 7003.984422258642, 7116.039334689957, 7118.25258347866, 7188.137423509213], "value": 5185.052177411717, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.1291772366817, 719.722906680928, 728.4281838756577, 733.8495608400278, 745.5787480073755, 14385.424583016667, 14895.329710163041, 15128.848711512896, 15158.393664253445, 15196.012259203144, 11533.538658453652, 13866.168029918212, 13876.16742995421, 13898.791518127236, 13915.93042034791, 704.2112833626346, 890.0965519997208, 925.9907400926005, 926.5969303353029, 928.0951072704127, 910.4218821048615, 919.4115765909823, 923.1091996223643, 924.2882980105314, 925.6482536636069, 960.2270172511161, 971.3443425687668, 974.4153507895242, 976.6191185437685, 980.9852852207187, 940.8118376324762, 961.6778379242927, 965.5124162298065, 967.7193613851974, 970.0154343341515, 703.5145749432875, 704.1374316462344, 723.3056265984661, 723.3309189000154, 725.9201487836334, 15215.08709477435, 15226.010309329859, 15230.619234519116, 15261.847381526153, 15402.0, 12543.93728031354, 12606.180598261084, 12799.232046077264, 12818.359082045954, 12911.160774549624, 9273.072692730673, 9682.903170968271, 9921.0, 9944.95027524865, 10149.441780702058, 3456.7061799747053, 3498.702610278129, 3514.2620049789575, 3564.750467467278, 3703.7222208334215, 75402.86895696569, 75737.86393204106, 75899.24100758976, 75999.0, 76064.23935760628, 8088.907997420273, 9232.399894006869, 9301.953490232505, 9414.905850941403, 9442.38624489406, 16888.662226755398, 20519.281825136048, 20752.0, 20995.950202489814, 21072.68390974106, 7544.547327160388, 7677.424193185533, 7681.692732290758, 7746.883796743054, 7862.449628525948, 4113.300738874398, 4132.834686612502, 4149.688773342012, 4205.600467955539, 4210.073783767566, 3540.8760693376125, 3590.1563132663805, 3591.6408359163875, 3597.2625611749527, 3603.621619729943, 557.183725841644, 640.1102467570081, 644.0886146103212, 649.0945131541473, 668.7191379620474, 580.216707444946, 593.0688818554828, 596.1862058290402, 611.5016261746662, 615.6613862375675, 7731.304182623591, 7875.409344299086, 7978.122406535276, 8101.6354264058255, 8151.551664658382, 539.9919001214985, 585.8440937350591, 595.3778301674673, 604.8760004199129, 611.1291409741101, 3584.211473475831, 3662.8168591570316, 3669.8532058717888, 3847.4228865669816, 3882.2429626222615, 84438.0, 85932.14067859367, 92735.0, 95680.0, 96220.0, 72926.27073729302, 73461.0, 74974.62512687402, 75376.62311688406, 75584.62207689036, 59554.0, 60258.0, 60397.094043588506, 60750.39249607536, 61108.69445652743, 7082.468814838805, 7454.9935758674055, 8200.589970501333, 8485.830283394422, 8507.659693612186, 35866.64133358633, 41167.382489263284, 41496.340146393806, 46357.0, 46520.83697907579, 41738.373924391766, 47636.5236347646, 57382.45669981909, 64837.0, 65051.34948650453, 10642.148628109888, 10682.73293167677, 10759.623413180501, 10957.232993690364, 10990.0, 11824.23142495735, 11837.881621183678, 11979.700507487383, 12009.939950300193, 12343.0, 10919.454027298445, 11149.10807135411, 11288.492017859051, 11470.197086203885, 11902.821457677968, 10309.381437113798, 11203.887961120285, 11266.0, 11406.771864562828, 11577.189596728485, 9974.301798874008, 10776.67669969902, 10848.891511085103, 11083.944580277048, 11350.772984540097, 9724.978877218073, 9725.513724313898, 9972.351797133164, 10125.79748405013, 10333.793324133625, 9805.509724513888, 9812.313138080266, 9826.606935722777, 9868.210543156674, 9886.110250077527], "value": 6756.9226939686, "unit": "score"}], "name": "dromaeo_css", "value": 7477.571433862087, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 102.0, 161.0, 173.0, 128.0, 130.0, 117.0, 116.0, 131.0, 98.0], "value": 127.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 132.0, 142.0, 212.0, 155.0, 173.0, 160.0, 156.0, 190.0, 155.0], "value": 162.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 200.0, 201.0, 207.0, 193.0, 197.0, 197.0, 194.0, 199.0, 195.0], "value": 198.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 79.0, 77.0, 169.0, 48.0, 57.0, 54.0, 56.0, 153.0, 48.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 86.0, 159.0, 116.0, 87.0, 89.0, 120.0, 104.0, 114.0, 160.0], "value": 111.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 79.0, 127.0, 131.0, 135.0, 118.0, 88.0, 120.0, 87.0, 84.0], "value": 105.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 93.0, 93.0, 94.0, 94.0, 103.0, 153.0, 153.0, 105.0], "value": 107.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 106.0, 92.0, 85.0, 151.0, 148.0, 92.0, 91.0, 91.0], "value": 104.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 67.0, 66.0, 65.0, 67.0, 64.0, 65.0, 74.0, 64.0, 61.0], "value": 65.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 69.0, 74.0, 72.0, 74.0, 69.0, 72.0, 74.0, 62.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 106.0, 97.0, 81.0, 83.0, 82.0, 84.0, 81.0, 85.0, 86.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 134.0, 116.0, 155.0, 148.0, 145.0, 132.0, 150.0, 182.0, 167.0], "value": 145.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 170.0, 169.0, 165.0, 221.0, 172.0, 172.0, 172.0, 167.0], "value": 174.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 70.0, 69.0, 65.0, 69.0, 66.0, 71.0, 69.0, 66.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1610.6000000000001, "alertThreshold": 2.0}]} 18:25:23 INFO - Return code: 0 18:25:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:25:23 ERROR - # TBPL SUCCESS # 18:25:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:25:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:25:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:25:23 INFO - 2016-05-06 18:25:23.873 screenresolution[2508:23363] starting screenresolution argv=screenresolution get 18:25:23 INFO - 2016-05-06 18:25:23.876 screenresolution[2508:23363] Display 0: 1600x1200x32@60 18:25:23 INFO - 2016-05-06 18:25:23.882 screenresolution[2509:23366] starting screenresolution argv=screenresolution list 18:25:23 INFO - Available Modes on Display 0 18:25:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:25:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:25:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:25:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:25:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:25:24 INFO - Intel Iris: 18:25:24 INFO - Chipset Model: Intel Iris 18:25:24 INFO - Type: GPU 18:25:24 INFO - Bus: Built-In 18:25:24 INFO - VRAM (Dynamic, Max): 1536 MB 18:25:24 INFO - Vendor: Intel (0x8086) 18:25:24 INFO - Device ID: 0x0a2e 18:25:24 INFO - Revision ID: 0x0009 18:25:24 INFO - Displays: 18:25:24 INFO - Display: 18:25:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:25:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:25:24 INFO - Display Serial Number: 1600x1200 60 18:25:24 INFO - Main Display: Yes 18:25:24 INFO - Mirror: Off 18:25:24 INFO - Online: Yes 18:25:24 INFO - Rotation: Supported 18:25:24 INFO - Return code: 0 18:25:24 INFO - Running post-action listener: _resource_record_post_action 18:25:24 INFO - Running post-run listener: _resource_record_post_run 18:25:25 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 380928; Write bytes: 654582784; Read time: 4; Write time: 1861 18:25:25 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342450688; Write bytes: 336914432; Read time: 26548; Write time: 575 18:25:25 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 380928; Write bytes: 298277888; Read time: 4; Write time: 1227 18:25:25 INFO - Running post-run listener: _upload_blobber_files 18:25:25 INFO - Blob upload gear active. 18:25:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:25:25 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 18:25:25 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'] 18:25:25 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 18:25:25 INFO - (blobuploader) - INFO - Open directory for files ... 18:25:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:25:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:25:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:26 INFO - (blobuploader) - INFO - Done attempting. 18:25:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:25:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:25:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:27 INFO - (blobuploader) - INFO - Done attempting. 18:25:27 INFO - (blobuploader) - INFO - Iteration through files over. 18:25:27 INFO - Return code: 0 18:25:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:25:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:25:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/53fc88e92b433e4b68a53b322bcc96d01d0a6924b273c5a651b23aef51ad44274db6213d9cef7c5badf4f9a8f92daeaec2099601775c667d131b14baae8687ff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0469daab71d74144f472cca8f6349028e3ac26bd78c8a3154fb92e427ab4d04fec024fb6674d781d167947f4b81e0e010dd3127b3984d971191ff3e951b36981"} 18:25:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:25:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:25:27 INFO - Contents: 18:25:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/53fc88e92b433e4b68a53b322bcc96d01d0a6924b273c5a651b23aef51ad44274db6213d9cef7c5badf4f9a8f92daeaec2099601775c667d131b14baae8687ff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0469daab71d74144f472cca8f6349028e3ac26bd78c8a3154fb92e427ab4d04fec024fb6674d781d167947f4b81e0e010dd3127b3984d971191ff3e951b36981"} 18:25:27 INFO - Running post-run listener: copy_logs_to_upload_dir 18:25:27 INFO - Copying logs to upload dir... 18:25:27 INFO - mkdir: /builds/slave/test/build/upload/logs 18:25:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.050739 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-06 18:25:27.599317) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 18:25:27.602885) ========= 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.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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/53fc88e92b433e4b68a53b322bcc96d01d0a6924b273c5a651b23aef51ad44274db6213d9cef7c5badf4f9a8f92daeaec2099601775c667d131b14baae8687ff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0469daab71d74144f472cca8f6349028e3ac26bd78c8a3154fb92e427ab4d04fec024fb6674d781d167947f4b81e0e010dd3127b3984d971191ff3e951b36981"} build_url:https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011448 build_url: 'https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/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/53fc88e92b433e4b68a53b322bcc96d01d0a6924b273c5a651b23aef51ad44274db6213d9cef7c5badf4f9a8f92daeaec2099601775c667d131b14baae8687ff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0469daab71d74144f472cca8f6349028e3ac26bd78c8a3154fb92e427ab4d04fec024fb6674d781d167947f4b81e0e010dd3127b3984d971191ff3e951b36981"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 18:25:27.629978) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:25:27.630271) ========= 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.2jpqFi1jXX/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.mLQXHYNsjQ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.005225 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:25:27.677892) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 18:25:27.678153) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 18:25:27.678408) ========= ========= Total master_lag: 0.16 =========