builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0014 starttime: 1461272913.23 results: success (0) buildid: 20160421130623 builduid: 80cedbe1cfe94b50bba3723e71cef2cd revision: b31b99289389a5e0ddab1ca9eccd4f03a6d81fe4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 14:08:33.231791) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 14:08:33.232222) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2016-04-21 14:08:33.232518) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2016-04-21 14:08:39.878218) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 14:08:39.878704) ========= 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.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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-04-21 14:08:39-- 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% 12.9M=0.001s 2016-04-21 14:08:40 (12.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.494763 ========= master_lag: 0.47 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 14:08:40.841350) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 14:08:40.841711) ========= 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.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.027440 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 14:08:40.942768) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-21 14:08:40.943114) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b31b99289389a5e0ddab1ca9eccd4f03a6d81fe4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev b31b99289389a5e0ddab1ca9eccd4f03a6d81fe4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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-04-21 14:08:41,042 truncating revision to first 12 chars 2016-04-21 14:08:41,042 Setting DEBUG logging. 2016-04-21 14:08:41,042 attempt 1/10 2016-04-21 14:08:41,042 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b31b99289389?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-21 14:08:42,040 unpacking tar archive at: fx-team-b31b99289389/testing/mozharness/ program finished with exit code 0 elapsedTime=1.248789 ========= master_lag: 4.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-21 14:08:46.245389) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 14:08:46.245754) ========= script_repo_revision: b31b99289389a5e0ddab1ca9eccd4f03a6d81fe4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 14:08:46.246165) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 27 secs) (at 2016-04-21 14:08:46.246453) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 27 secs) (at 2016-04-21 14:09:14.153499) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-04-21 14:09:14.153926) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:09:15 INFO - MultiFileLogger online at 20160421 14:09:15 in /builds/slave/test 14:09:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 14:09:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:09:15 INFO - {'append_to_log': False, 14:09:15 INFO - 'base_work_dir': '/builds/slave/test', 14:09:15 INFO - 'blob_upload_branch': 'Fx-Team', 14:09:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:09:15 INFO - 'branch': 'Fx-Team', 14:09:15 INFO - 'buildbot_json_path': 'buildprops.json', 14:09:15 INFO - 'config_files': ('talos/mac_config.py',), 14:09:15 INFO - 'default_actions': ('clobber', 14:09:15 INFO - 'read-buildbot-config', 14:09:15 INFO - 'download-and-extract', 14:09:15 INFO - 'populate-webroot', 14:09:15 INFO - 'create-virtualenv', 14:09:15 INFO - 'install', 14:09:15 INFO - 'run-tests'), 14:09:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:09:15 INFO - 'download_minidump_stackwalk': True, 14:09:15 INFO - 'download_symbols': 'ondemand', 14:09:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:09:15 INFO - 'tooltool.py': '/tools/tooltool.py', 14:09:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:09:15 INFO - '/tools/misc-python/virtualenv.py')}, 14:09:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:09:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:09:15 INFO - 'installer_path': 'installer.exe', 14:09:15 INFO - 'log_level': 'info', 14:09:15 INFO - 'log_name': 'talos', 14:09:15 INFO - 'log_to_console': True, 14:09:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:09:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:09:15 INFO - 'opt_config_files': (), 14:09:15 INFO - 'pip_index': False, 14:09:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:09:15 INFO - 'cmd': ('bash', 14:09:15 INFO - '-c', 14:09:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:09:15 INFO - 'enabled': True, 14:09:15 INFO - 'halt_on_failure': False, 14:09:15 INFO - 'name': 'check_screen_resolution'},), 14:09:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:09:15 INFO - 'cmd': ('bash', 14:09:15 INFO - '-c', 14:09:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:09:15 INFO - 'enabled': True, 14:09:15 INFO - 'halt_on_failure': False, 14:09:15 INFO - 'name': 'check_screen_resolution'},), 14:09:15 INFO - 'run_cmd_checks_enabled': True, 14:09:15 INFO - 'sps_profile': False, 14:09:15 INFO - 'sps_profile_interval': 0, 14:09:15 INFO - 'suite': 'dromaeojs', 14:09:15 INFO - 'system_bits': '32', 14:09:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:09:15 INFO - 'title': 't-yosemite-r7-0014', 14:09:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:09:15 INFO - 'use_talos_json': True, 14:09:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:09:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:09:15 INFO - 'work_dir': 'build'} 14:09:15 INFO - ##### 14:09:15 INFO - ##### Running clobber step. 14:09:15 INFO - ##### 14:09:15 INFO - Running pre-action listener: _resource_record_pre_action 14:09:15 INFO - Running main action method: clobber 14:09:15 INFO - rmtree: /builds/slave/test/build 14:09:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:09:17 INFO - Running post-action listener: _resource_record_post_action 14:09:17 INFO - ##### 14:09:17 INFO - ##### Running read-buildbot-config step. 14:09:17 INFO - ##### 14:09:17 INFO - Running pre-action listener: _resource_record_pre_action 14:09:17 INFO - Running main action method: read_buildbot_config 14:09:17 INFO - Using buildbot properties: 14:09:17 INFO - { 14:09:17 INFO - "project": "", 14:09:17 INFO - "product": "firefox", 14:09:17 INFO - "script_repo_revision": "production", 14:09:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 14:09:17 INFO - "repository": "", 14:09:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 14:09:17 INFO - "buildid": "20160421130623", 14:09:17 INFO - "pgo_build": "False", 14:09:17 INFO - "basedir": "/builds/slave/test", 14:09:17 INFO - "buildnumber": 751, 14:09:17 INFO - "slavename": "t-yosemite-r7-0014", 14:09:17 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 14:09:17 INFO - "platform": "yosemite_r7", 14:09:17 INFO - "scheduler": "tests-fx-team-macosx64-talos", 14:09:17 INFO - "branch": "fx-team", 14:09:17 INFO - "repo_path": "integration/fx-team", 14:09:17 INFO - "revision": "b31b99289389a5e0ddab1ca9eccd4f03a6d81fe4", 14:09:17 INFO - "stage_platform": "macosx64", 14:09:17 INFO - "builduid": "80cedbe1cfe94b50bba3723e71cef2cd", 14:09:17 INFO - "slavebuilddir": "test" 14:09:17 INFO - } 14:09:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 14:09:17 INFO - Running post-action listener: _resource_record_post_action 14:09:17 INFO - ##### 14:09:17 INFO - ##### Running download-and-extract step. 14:09:17 INFO - ##### 14:09:17 INFO - Running pre-action listener: _resource_record_pre_action 14:09:17 INFO - Running main action method: download_and_extract 14:09:17 INFO - mkdir: /builds/slave/test/build/tests 14:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:09:17 INFO - https://queue.taskcluster.net/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:09:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 14:09:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 14:09:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 14:09:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 14:09:21 INFO - Downloaded 1199 bytes. 14:09:21 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 14:09:21 INFO - Using the following test package requirements: 14:09:21 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 14:09:21 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:09:21 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 14:09:21 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:09:21 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 14:09:21 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:09:21 INFO - u'jsshell-mac.zip'], 14:09:21 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:09:21 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 14:09:21 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 14:09:21 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:09:21 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 14:09:21 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:09:21 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 14:09:21 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:09:21 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 14:09:21 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:09:21 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 14:09:21 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:09:21 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')]} 14:09:21 INFO - https://queue.taskcluster.net/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:09:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 14:09:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 14:09:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 14:09:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:09:26 INFO - Downloaded 18099899 bytes. 14:09:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:09:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:09:29 INFO - Return code: 0 14:09:29 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:29 INFO - https://queue.taskcluster.net/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:09:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 14:09:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 14:09:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 14:09:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:09:30 INFO - Downloaded 18099899 bytes. 14:09:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:09:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:09:33 INFO - Return code: 0 14:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:33 INFO - https://queue.taskcluster.net/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:09:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 14:09:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 14:09:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 14:09:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:09:35 INFO - Downloaded 10845952 bytes. 14:09:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:09:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:09:35 INFO - Return code: 0 14:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:09:35 INFO - https://queue.taskcluster.net/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:09:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 14:09:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 14:09:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 14:09:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 14:09:39 INFO - Downloaded 109956110 bytes. 14:09:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 14:09:39 INFO - mkdir: /builds/slave/test/properties 14:09:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:09:39 INFO - Writing to file /builds/slave/test/properties/build_url 14:09:39 INFO - Contents: 14:09:39 INFO - build_url:https://queue.taskcluster.net/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 14:09:40 INFO - Running post-action listener: _resource_record_post_action 14:09:40 INFO - Running post-action listener: set_extra_try_arguments 14:09:40 INFO - ##### 14:09:40 INFO - ##### Running populate-webroot step. 14:09:40 INFO - ##### 14:09:40 INFO - Running pre-action listener: _resource_record_pre_action 14:09:40 INFO - Running main action method: populate_webroot 14:09:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:09:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:09:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:09:40 INFO - '--tppagecycles', 14:09:40 INFO - '7'], 14:09:40 INFO - 'tests': ['tsvgm']}}, 14:09:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:09:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:09:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:09:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:09:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:09:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:09:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['damp', 'tps']}, 14:09:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'tests': ['damp', 'tps']}, 14:09:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['dromaeo_dom']}, 14:09:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:09:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['a11yr', 14:09:40 INFO - 'ts_paint', 14:09:40 INFO - 'tpaint', 14:09:40 INFO - 'sessionrestore', 14:09:40 INFO - 'sessionrestore_no_auto_restore', 14:09:40 INFO - 'tabpaint']}, 14:09:40 INFO - 'other-e10s': {'tests': ['a11yr', 14:09:40 INFO - 'ts_paint', 14:09:40 INFO - 'tpaint', 14:09:40 INFO - 'sessionrestore', 14:09:40 INFO - 'sessionrestore_no_auto_restore', 14:09:40 INFO - 'tabpaint']}, 14:09:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:09:40 INFO - 'ts_paint', 14:09:40 INFO - 'tpaint', 14:09:40 INFO - 'sessionrestore', 14:09:40 INFO - 'sessionrestore_no_auto_restore', 14:09:40 INFO - 'tabpaint']}, 14:09:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:09:40 INFO - 'ts_paint', 14:09:40 INFO - 'tpaint', 14:09:40 INFO - 'sessionrestore', 14:09:40 INFO - 'sessionrestore_no_auto_restore', 14:09:40 INFO - 'tabpaint']}, 14:09:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['a11yr', 14:09:40 INFO - 'ts_paint', 14:09:40 INFO - 'tpaint', 14:09:40 INFO - 'sessionrestore', 14:09:40 INFO - 'sessionrestore_no_auto_restore', 14:09:40 INFO - 'tabpaint']}, 14:09:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['a11yr', 14:09:40 INFO - 'ts_paint', 14:09:40 INFO - 'tpaint', 14:09:40 INFO - 'sessionrestore', 14:09:40 INFO - 'sessionrestore_no_auto_restore', 14:09:40 INFO - 'tabpaint']}, 14:09:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['tsvgx', 14:09:40 INFO - 'tsvgr_opacity', 14:09:40 INFO - 'tart', 14:09:40 INFO - 'tscrollx', 14:09:40 INFO - 'cart']}, 14:09:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:09:40 INFO - 'tsvgr_opacity', 14:09:40 INFO - 'tart', 14:09:40 INFO - 'tscrollx', 14:09:40 INFO - 'cart']}, 14:09:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'talos_options': ['--disable-e10s'], 14:09:40 INFO - 'tests': ['tp5o']}, 14:09:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'tests': ['tp5o']}, 14:09:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'talos_options': ['--disable-e10s', 14:09:40 INFO - '--xperf_path', 14:09:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:09:40 INFO - 'tests': ['tp5n']}, 14:09:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:40 INFO - 'talos_options': ['--xperf_path', 14:09:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:09:40 INFO - 'tests': ['tp5n']}}, 14:09:40 INFO - 'talos.zip': {'path': '', 14:09:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:09:40 INFO - Running post-action listener: _resource_record_post_action 14:09:40 INFO - ##### 14:09:40 INFO - ##### Running create-virtualenv step. 14:09:40 INFO - ##### 14:09:40 INFO - Running pre-action listener: _resource_record_pre_action 14:09:40 INFO - Running main action method: create_virtualenv 14:09:40 INFO - Creating virtualenv /builds/slave/test/build/venv 14:09:40 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 14:09:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:09:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:09:40 INFO - Using real prefix '/tools/python27' 14:09:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:09:41 INFO - Installing distribute.............................................................................................................................................................................................done. 14:09:44 INFO - Installing pip.................done. 14:09:44 INFO - Return code: 0 14:09:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cebca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa482d98a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5be00>, '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 0x10cb84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd56b20>, '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.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:09:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:09:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:09:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qxy1QXnHX7/Render', 14:09:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:44 INFO - 'HOME': '/Users/cltbld', 14:09:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:44 INFO - 'LOGNAME': 'cltbld', 14:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:44 INFO - 'MOZ_NO_REMOTE': '1', 14:09:44 INFO - 'NO_EM_RESTART': '1', 14:09:44 INFO - 'PAGER': '/bin/cat', 14:09:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:44 INFO - 'PWD': '/builds/slave/test', 14:09:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:44 INFO - 'SHELL': '/bin/bash', 14:09:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1C8mxqFlh/Listeners', 14:09:44 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:09:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:44 INFO - 'USER': 'cltbld', 14:09:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:44 INFO - 'XPC_FLAGS': '0x0', 14:09:44 INFO - 'XPC_SERVICE_NAME': '0', 14:09:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:44 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 14:09:44 INFO - Cleaning up... 14:09:44 INFO - Return code: 0 14:09:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cebca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa482d98a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5be00>, '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 0x10cb84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd56b20>, '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.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:09:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 14:09:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 14:09:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qxy1QXnHX7/Render', 14:09:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:44 INFO - 'HOME': '/Users/cltbld', 14:09:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:44 INFO - 'LOGNAME': 'cltbld', 14:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:44 INFO - 'MOZ_NO_REMOTE': '1', 14:09:44 INFO - 'NO_EM_RESTART': '1', 14:09:44 INFO - 'PAGER': '/bin/cat', 14:09:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:44 INFO - 'PWD': '/builds/slave/test', 14:09:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:44 INFO - 'SHELL': '/bin/bash', 14:09:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1C8mxqFlh/Listeners', 14:09:44 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:09:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:44 INFO - 'USER': 'cltbld', 14:09:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:44 INFO - 'XPC_FLAGS': '0x0', 14:09:44 INFO - 'XPC_SERVICE_NAME': '0', 14:09:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:45 INFO - Downloading/unpacking psutil>=0.7.1 14:09:45 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 14:09:45 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 14:09:45 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 14:09:45 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 14:09:45 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 14:09:45 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 14:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:09:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:48 INFO - Installing collected packages: psutil 14:09:48 INFO - Running setup.py install for psutil 14:09:48 INFO - building 'psutil._psutil_osx' extension 14:09:48 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 14:09:49 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 14:09:49 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 14:09:49 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 14:09:49 INFO - building 'psutil._psutil_posix' extension 14:09:49 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 14:09:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:09:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:09:49 INFO - ^ 14:09:49 INFO - 1 warning generated. 14:09:49 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 14:09:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:49 INFO - Successfully installed psutil 14:09:49 INFO - Cleaning up... 14:09:49 INFO - Return code: 0 14:09:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:09: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')]} 14:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09: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')]} 14:09:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:49 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 0x10cebca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa482d98a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5be00>, '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 0x10cb84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd56b20>, '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.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:09:49 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 14:09:49 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 14:09:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qxy1QXnHX7/Render', 14:09:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:49 INFO - 'HOME': '/Users/cltbld', 14:09:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:49 INFO - 'LOGNAME': 'cltbld', 14:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:49 INFO - 'MOZ_NO_REMOTE': '1', 14:09:49 INFO - 'NO_EM_RESTART': '1', 14:09:49 INFO - 'PAGER': '/bin/cat', 14:09:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:49 INFO - 'PWD': '/builds/slave/test', 14:09:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:49 INFO - 'SHELL': '/bin/bash', 14:09:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1C8mxqFlh/Listeners', 14:09:49 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:09:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:49 INFO - 'USER': 'cltbld', 14:09:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:49 INFO - 'XPC_FLAGS': '0x0', 14:09:49 INFO - 'XPC_SERVICE_NAME': '0', 14:09:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:09:49 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 14:09:49 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 14:09:49 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 14:09:49 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 14:09:49 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 14:09:49 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 14:09:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:09:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:09:52 INFO - Installing collected packages: mozsystemmonitor 14:09:52 INFO - Running setup.py install for mozsystemmonitor 14:09:52 INFO - Successfully installed mozsystemmonitor 14:09:52 INFO - Cleaning up... 14:09:52 INFO - Return code: 0 14:09:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:52 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 0x10cebca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa482d98a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5be00>, '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 0x10cb84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd56b20>, '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.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:09:52 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 14:09:52 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 14:09:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qxy1QXnHX7/Render', 14:09:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:52 INFO - 'HOME': '/Users/cltbld', 14:09:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:52 INFO - 'LOGNAME': 'cltbld', 14:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:52 INFO - 'MOZ_NO_REMOTE': '1', 14:09:52 INFO - 'NO_EM_RESTART': '1', 14:09:52 INFO - 'PAGER': '/bin/cat', 14:09:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:52 INFO - 'PWD': '/builds/slave/test', 14:09:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:52 INFO - 'SHELL': '/bin/bash', 14:09:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1C8mxqFlh/Listeners', 14:09:52 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:09:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:52 INFO - 'USER': 'cltbld', 14:09:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:52 INFO - 'XPC_FLAGS': '0x0', 14:09:52 INFO - 'XPC_SERVICE_NAME': '0', 14:09:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:52 INFO - Downloading/unpacking blobuploader==1.2.4 14:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:55 INFO - Downloading blobuploader-1.2.4.tar.gz 14:09:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:09:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:09:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:56 INFO - Downloading docopt-0.6.1.tar.gz 14:09:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:09:56 INFO - Installing collected packages: blobuploader, requests, docopt 14:09:56 INFO - Running setup.py install for blobuploader 14:09:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:09:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:09:56 INFO - Running setup.py install for requests 14:09:57 INFO - Running setup.py install for docopt 14:09:57 INFO - Successfully installed blobuploader requests docopt 14:09:57 INFO - Cleaning up... 14:09:57 INFO - Return code: 0 14:09:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:57 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 0x10cebca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa482d98a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5be00>, '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 0x10cb84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd56b20>, '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.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:09:57 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 14:09:57 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 14:09:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qxy1QXnHX7/Render', 14:09:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:57 INFO - 'HOME': '/Users/cltbld', 14:09:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:57 INFO - 'LOGNAME': 'cltbld', 14:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:57 INFO - 'MOZ_NO_REMOTE': '1', 14:09:57 INFO - 'NO_EM_RESTART': '1', 14:09:57 INFO - 'PAGER': '/bin/cat', 14:09:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:57 INFO - 'PWD': '/builds/slave/test', 14:09:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:57 INFO - 'SHELL': '/bin/bash', 14:09:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1C8mxqFlh/Listeners', 14:09:57 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:09:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:57 INFO - 'USER': 'cltbld', 14:09:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:57 INFO - 'XPC_FLAGS': '0x0', 14:09:57 INFO - 'XPC_SERVICE_NAME': '0', 14:09:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:09:57 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-MMSR1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:09:57 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-JwUm8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:09:57 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Zf0b26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:09:57 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-RPWdlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:09:58 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-5b8Ofw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:09:58 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-3uKag2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:09:58 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-W2rtly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:09:58 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-mkrzzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:09:58 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-m9sg9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:09:58 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-GUKqex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:09:58 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Nsqztq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:09:58 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-t1PHJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:09:58 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-w8KoNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:09:58 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-oh8VwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:59 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-dfxUCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:09:59 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-AWCFqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:09:59 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-zIrcKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:09:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:09:59 INFO - Running setup.py install for manifestparser 14:09:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:09:59 INFO - Running setup.py install for mozcrash 14:09:59 INFO - Running setup.py install for mozdebug 14:09:59 INFO - Running setup.py install for mozdevice 14:10:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:10:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:10:00 INFO - Running setup.py install for mozfile 14:10:00 INFO - Running setup.py install for mozhttpd 14:10:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:10:00 INFO - Running setup.py install for mozinfo 14:10:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:10:00 INFO - Running setup.py install for mozInstall 14:10:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:10:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:10:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:10:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:10:00 INFO - Running setup.py install for mozleak 14:10:00 INFO - Running setup.py install for mozlog 14:10:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:10:00 INFO - Running setup.py install for moznetwork 14:10:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:10:01 INFO - Running setup.py install for mozprocess 14:10:01 INFO - Running setup.py install for mozprofile 14:10:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:10:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:10:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:10:01 INFO - Running setup.py install for mozrunner 14:10:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:10:01 INFO - Running setup.py install for mozscreenshot 14:10:01 INFO - Running setup.py install for moztest 14:10:01 INFO - Running setup.py install for mozversion 14:10:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:10:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:10:01 INFO - Cleaning up... 14:10:01 INFO - Return code: 0 14:10:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cebca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa482d98a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5be00>, '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 0x10cb84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd56b20>, '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.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:10:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qxy1QXnHX7/Render', 14:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:10:01 INFO - 'HOME': '/Users/cltbld', 14:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:10:01 INFO - 'LOGNAME': 'cltbld', 14:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:01 INFO - 'MOZ_NO_REMOTE': '1', 14:10:01 INFO - 'NO_EM_RESTART': '1', 14:10:01 INFO - 'PAGER': '/bin/cat', 14:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:01 INFO - 'PWD': '/builds/slave/test', 14:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:10:01 INFO - 'SHELL': '/bin/bash', 14:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1C8mxqFlh/Listeners', 14:10:01 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:10:01 INFO - 'USER': 'cltbld', 14:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:01 INFO - 'XPC_FLAGS': '0x0', 14:10:01 INFO - 'XPC_SERVICE_NAME': '0', 14:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:10:02 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-tvkKq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:10:02 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)) 14:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:10:02 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Hz0q63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:10:02 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)) 14:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:10:02 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-rhvHpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:10:02 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-LGu1fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:10:02 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)) 14:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:10:02 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-xHorME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:10:02 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-XXUsu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:10:02 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-_txc2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:10:02 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-VQ3elp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:10:03 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)) 14:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:10:03 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ug1euO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:10:03 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)) 14:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:10:03 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-enIgLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:10:03 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)) 14:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:10:03 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-LpIHl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:10:03 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)) 14:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:10:03 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-M8tMgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:10:03 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)) 14:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:10:03 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-3FJ1Zk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:10:03 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-rXLIAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:10:03 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)) 14:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:10:03 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-a1bs2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:10:03 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-VTI9GL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:10:03 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)) 14:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:10:03 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-MFrjio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:10:04 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)) 14:10:04 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)) 14:10:04 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)) 14:10:04 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)) 14:10:04 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)) 14:10:04 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)) 14:10:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:10:04 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 14:10:04 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 14:10:04 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 14:10:04 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 14:10:04 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 14:10:04 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 14:10:06 INFO - Downloading blessings-1.6.tar.gz 14:10:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:10:06 INFO - Installing collected packages: blessings 14:10:06 INFO - Running setup.py install for blessings 14:10:06 INFO - Successfully installed blessings 14:10:06 INFO - Cleaning up... 14:10:06 INFO - Return code: 0 14:10:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:10:07 INFO - Reading from file tmpfile_stdout 14:10:07 INFO - Current package versions: 14:10:07 INFO - blessings == 1.6 14:10:07 INFO - blobuploader == 1.2.4 14:10:07 INFO - docopt == 0.6.1 14:10:07 INFO - manifestparser == 1.1 14:10:07 INFO - mozInstall == 1.12 14:10:07 INFO - mozcrash == 0.17 14:10:07 INFO - mozdebug == 0.1 14:10:07 INFO - mozdevice == 0.48 14:10:07 INFO - mozfile == 1.2 14:10:07 INFO - mozhttpd == 0.7 14:10:07 INFO - mozinfo == 0.9 14:10:07 INFO - mozleak == 0.1 14:10:07 INFO - mozlog == 3.1 14:10:07 INFO - moznetwork == 0.27 14:10:07 INFO - mozprocess == 0.22 14:10:07 INFO - mozprofile == 0.28 14:10:07 INFO - mozrunner == 6.11 14:10:07 INFO - mozscreenshot == 0.1 14:10:07 INFO - mozsystemmonitor == 0.0 14:10:07 INFO - moztest == 0.7 14:10:07 INFO - mozversion == 1.4 14:10:07 INFO - psutil == 3.1.1 14:10:07 INFO - requests == 1.2.3 14:10:07 INFO - wsgiref == 0.1.2 14:10:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cebca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa482d98a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5be00>, '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 0x10cb84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd56b20>, '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.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:10:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qxy1QXnHX7/Render', 14:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:10:07 INFO - 'HOME': '/Users/cltbld', 14:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:10:07 INFO - 'LOGNAME': 'cltbld', 14:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:07 INFO - 'MOZ_NO_REMOTE': '1', 14:10:07 INFO - 'NO_EM_RESTART': '1', 14:10:07 INFO - 'PAGER': '/bin/cat', 14:10:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:07 INFO - 'PWD': '/builds/slave/test', 14:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:10:07 INFO - 'SHELL': '/bin/bash', 14:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1C8mxqFlh/Listeners', 14:10:07 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:10:07 INFO - 'USER': 'cltbld', 14:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:07 INFO - 'XPC_FLAGS': '0x0', 14:10:07 INFO - 'XPC_SERVICE_NAME': '0', 14:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:10:07 INFO - Cleaning up... 14:10:07 INFO - Return code: 0 14:10:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:07 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 0x10cebca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa482d98a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5be00>, '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 0x10cb84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd56b20>, '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.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:10:07 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 14:10:07 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 14:10:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qxy1QXnHX7/Render', 14:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:10:07 INFO - 'HOME': '/Users/cltbld', 14:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:10:07 INFO - 'LOGNAME': 'cltbld', 14:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:07 INFO - 'MOZ_NO_REMOTE': '1', 14:10:07 INFO - 'NO_EM_RESTART': '1', 14:10:07 INFO - 'PAGER': '/bin/cat', 14:10:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:07 INFO - 'PWD': '/builds/slave/test', 14:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:10:07 INFO - 'SHELL': '/bin/bash', 14:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1C8mxqFlh/Listeners', 14:10:07 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:10:07 INFO - 'USER': 'cltbld', 14:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:07 INFO - 'XPC_FLAGS': '0x0', 14:10:07 INFO - 'XPC_SERVICE_NAME': '0', 14:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:07 INFO - Downloading/unpacking jsonschema 14:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:10:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:10:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:10:09 INFO - Downloading/unpacking functools32 (from jsonschema) 14:10: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 14:10: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 14:10: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 14:10: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 14:10: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 14:10: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 14:10:10 INFO - Downloading functools32-3.2.3-2.tar.gz 14:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:10:10 INFO - warning: no files found matching '*.txt' 14:10:10 INFO - no previously-included directories found matching 'build' 14:10:10 INFO - no previously-included directories found matching 'dist' 14:10:10 INFO - no previously-included directories found matching '.git*' 14:10:10 INFO - Installing collected packages: jsonschema, functools32 14:10:10 INFO - Running setup.py install for functools32 14:10:10 INFO - warning: no files found matching '*.txt' 14:10:10 INFO - no previously-included directories found matching 'build' 14:10:10 INFO - no previously-included directories found matching 'dist' 14:10:10 INFO - no previously-included directories found matching '.git*' 14:10:10 INFO - Successfully installed jsonschema functools32 14:10:10 INFO - Cleaning up... 14:10:10 INFO - Return code: 0 14:10:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:10:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:10:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:10:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:10:10 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 0x10cebca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cecc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa482d98a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd5be00>, '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 0x10cb84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd56b20>, '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.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 14:10:10 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 14:10:10 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 14:10:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qxy1QXnHX7/Render', 14:10:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:10:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:10:10 INFO - 'HOME': '/Users/cltbld', 14:10:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:10:10 INFO - 'LOGNAME': 'cltbld', 14:10:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:10 INFO - 'MOZ_NO_REMOTE': '1', 14:10:10 INFO - 'NO_EM_RESTART': '1', 14:10:10 INFO - 'PAGER': '/bin/cat', 14:10:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:10:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:10 INFO - 'PWD': '/builds/slave/test', 14:10:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:10:10 INFO - 'SHELL': '/bin/bash', 14:10:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1C8mxqFlh/Listeners', 14:10:10 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:10:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:10:10 INFO - 'USER': 'cltbld', 14:10:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:10:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:10:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:10 INFO - 'XPC_FLAGS': '0x0', 14:10:10 INFO - 'XPC_SERVICE_NAME': '0', 14:10:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:10:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:10:10 INFO - Downloading/unpacking flake8 14:10:10 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 14:10:10 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 14:10:10 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 14:10:10 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 14:10:10 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 14:10:10 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 14:10:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:10:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:10:13 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 14:10:13 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 14:10:13 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 14:10:13 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 14:10:13 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 14:10:13 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 14:10:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:10:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:10:13 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 14:10:13 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 14:10:13 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 14:10:13 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 14:10:13 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 14:10:13 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 14:10:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:10:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:10:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:10:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:10:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:10:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:10:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:10:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:10:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:10:14 INFO - no previously-included directories found matching 'docs/_build' 14:10:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:10:14 INFO - Running setup.py install for pep8 14:10:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:10:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:10:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:10:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:10:14 INFO - no previously-included directories found matching 'docs/_build' 14:10:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:10:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:10:14 INFO - Cleaning up... 14:10:15 INFO - Return code: 0 14:10:15 INFO - Running post-action listener: _resource_record_post_action 14:10:15 INFO - Running post-action listener: _start_resource_monitoring 14:10:15 INFO - Starting resource monitoring. 14:10:15 INFO - ##### 14:10:15 INFO - ##### Running install step. 14:10:15 INFO - ##### 14:10:15 INFO - Running pre-action listener: _resource_record_pre_action 14:10:15 INFO - Running main action method: install 14:10:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:10:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:10:15 INFO - Reading from file tmpfile_stdout 14:10:15 INFO - Detecting whether we're running mozinstall >=1.0... 14:10:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:10:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:10:15 INFO - Reading from file tmpfile_stdout 14:10:15 INFO - Output received: 14:10:15 INFO - Usage: mozinstall [options] installer 14:10:15 INFO - Options: 14:10:15 INFO - -h, --help show this help message and exit 14:10:15 INFO - -d DEST, --destination=DEST 14:10:15 INFO - Directory to install application into. [default: 14:10:15 INFO - "/builds/slave/test"] 14:10:15 INFO - --app=APP Application being installed. [default: firefox] 14:10:15 INFO - mkdir: /builds/slave/test/build/application 14:10:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:10:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:10:48 INFO - Reading from file tmpfile_stdout 14:10:48 INFO - Output received: 14:10:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:10:48 INFO - Running post-action listener: _resource_record_post_action 14:10:48 INFO - ##### 14:10:48 INFO - ##### Running run-tests step. 14:10:48 INFO - ##### 14:10:48 INFO - Running pre-action listener: _resource_record_pre_action 14:10:48 INFO - Running main action method: run_tests 14:10:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:10:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:10:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:10:48 INFO - 2016-04-21 14:10:48.970 screenresolution[1650:8443] starting screenresolution argv=screenresolution get 14:10:48 INFO - 2016-04-21 14:10:48.973 screenresolution[1650:8443] Display 0: 1600x1200x32@60 14:10:48 INFO - 2016-04-21 14:10:48.979 screenresolution[1651:8445] starting screenresolution argv=screenresolution list 14:10:48 INFO - Available Modes on Display 0 14:10:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:10:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:10:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:10:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:10:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:10:49 INFO - Intel Iris: 14:10:49 INFO - Chipset Model: Intel Iris 14:10:49 INFO - Type: GPU 14:10:49 INFO - Bus: Built-In 14:10:49 INFO - VRAM (Dynamic, Max): 1536 MB 14:10:49 INFO - Vendor: Intel (0x8086) 14:10:49 INFO - Device ID: 0x0a2e 14:10:49 INFO - Revision ID: 0x0009 14:10:49 INFO - Displays: 14:10:49 INFO - Display: 14:10:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:10:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:10:49 INFO - Display Serial Number: 1600x1200 60 14:10:49 INFO - Main Display: Yes 14:10:49 INFO - Mirror: Off 14:10:49 INFO - Online: Yes 14:10:49 INFO - Rotation: Supported 14:10:49 INFO - Return code: 0 14:10:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:10:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:10:49 INFO - Python 2.7.3 14:10:49 INFO - Return code: 0 14:10:49 INFO - grabbing minidump binary from tooltool 14:10: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')]} 14:10: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 0x10cd5be00>, '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 0x10cb84db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd56b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:10: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 14:10: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 14:10:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:10:49 INFO - Return code: 0 14:10:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:10:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:10:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:10:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:10:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:10:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:10:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:10:50 INFO - Return code: 0 14:10:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:10:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0014 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:10:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qxy1QXnHX7/Render', 14:10:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:10:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:10:50 INFO - 'HOME': '/Users/cltbld', 14:10:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:10:50 INFO - 'LOGNAME': 'cltbld', 14:10:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:10:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:10:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:50 INFO - 'MOZ_NO_REMOTE': '1', 14:10:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:10:50 INFO - 'NO_EM_RESTART': '1', 14:10:50 INFO - 'PAGER': '/bin/cat', 14:10:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:10:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:50 INFO - 'PWD': '/builds/slave/test', 14:10:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:10:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:10:50 INFO - 'SHELL': '/bin/bash', 14:10:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U1C8mxqFlh/Listeners', 14:10:50 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 14:10:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:10:50 INFO - 'USER': 'cltbld', 14:10:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:10:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:10:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:50 INFO - 'XPC_FLAGS': '0x0', 14:10:50 INFO - 'XPC_SERVICE_NAME': '0', 14:10:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:10:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:10:50 INFO - mozversion application_buildid: 20160421130623 14:10:50 INFO - mozversion application_changeset: b31b99289389a5e0ddab1ca9eccd4f03a6d81fe4 14:10:50 INFO - mozversion application_display_name: Nightly 14:10:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:10:50 INFO - mozversion application_name: Firefox 14:10:50 INFO - mozversion application_remotingname: firefox 14:10:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:10:50 INFO - mozversion application_vendor: Mozilla 14:10:50 INFO - mozversion application_version: 48.0a1 14:10:50 INFO - mozversion platform_buildid: 20160421130623 14:10:50 INFO - mozversion platform_changeset: b31b99289389a5e0ddab1ca9eccd4f03a6d81fe4 14:10:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:10:50 INFO - mozversion platform_version: 48.0a1 14:10:50 INFO - using testdate: 1461273050 14:10:50 INFO - actual date: 1461273050 14:10:50 INFO - starting webserver on 'localhost:49275' 14:10:50 INFO - SUITE-START | Running 2 tests 14:10:50 INFO - TEST-START | dromaeo_css 14:10:50 INFO - operating with platform_type : mac_ 14:10:50 INFO - Initialising browser for dromaeo_css test... 14:10:50 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpTI6sk5/profile http://localhost:49275/getInfo.html) 14:10:53 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 14:10:53 INFO - PROCESS | 1669 | colorDepth:24 14:10:53 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 14:10:53 INFO - PROCESS | 1669 | __metrics 14:10:53 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:10:53 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:10:53 INFO - TEST-INFO | 1669: exit 0 14:10:53 INFO - Browser initialized. 14:10:53 INFO - Running cycle 1/1 for dromaeo_css test... 14:10:53 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpTI6sk5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:11:05 INFO - PROCESS | 1673 | RSS: Main: 238694400 14:11:05 INFO - PROCESS | 1673 | 14:11:57 INFO - PROCESS | 1673 | 1461273117480 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] 14:14:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 14:14:09 INFO - PROCESS | 1673 | RSS: Main: 425906176 14:14:09 INFO - PROCESS | 1673 | 14:17:13 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 14:17:13 INFO - PROCESS | 1673 | RSS: Main: 377225216 14:17:13 INFO - PROCESS | 1673 | 14:20:15 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 14:20:16 INFO - PROCESS | 1673 | RSS: Main: 378433536 14:20:16 INFO - PROCESS | 1673 | 14:23:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 14:23:19 INFO - PROCESS | 1673 | RSS: Main: 379195392 14:23:19 INFO - PROCESS | 1673 | 14:26:22 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 14:26:23 INFO - PROCESS | 1673 | RSS: Main: 345333760 14:26:23 INFO - PROCESS | 1673 | 14:29:26 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 14:29:26 INFO - PROCESS | 1673 | RSS: Main: 383926272 14:29:26 INFO - PROCESS | 1673 | 14:29:26 INFO - PROCESS | 1673 | __start_tp_report 14:29:26 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 14:29:26 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 14:29:26 INFO - PROCESS | 1673 | |i|pagename|runs| 14:29:26 INFO - PROCESS | 1673 | |0;dojo.html;2949.39537394834;3119.9688003119977;3386.187315044387;3446.276281980785;3480.3909315869732;5543.974364742526;5597.804076857311;5600.0759874620635;5607.523360514356;5651.9152212716845;5557.944420555793;5618.0449323614985;5650.321961364642;5653.830385088454;5667.716614169285;5094.719790411516;5110.1312776827845;5148.768305426265;5168.3539557555305;5197.298364720771;5172.172452407615;5199.454057323983;5200.245964335169;5206.7656955436905;5214.921776173361;5120.001599688062;5170.327857378536;5174.534291913727;5183.429822719498;5253.7373131343475;5193.714345710965;5202.739863006835;5213.478652134756;5214.765335559871;5271.28837606922;5020.7740651670765;5052.722100284483;5110.233464980282;5114.130597798383;5139.794408223666;4665.4168228971375;5509;5552.611317207797;5563.527100196488;5576.972115139439;6130;6152.3540028297275;6158.84602884927;6172.814815555495;6176.783812566584;6254.499640028786;6257.436830685215;6258.749650013994;6261.749530018794;6264.3422440643535;5563.582731295169;5580.972095139539;5636.379998200195;5653.802116925888;5674.177244299594;6253.937460625427;6258.249010118815;6275.435210831047;6282.748690052346;6291.653959032205;6089;6090.056041313604;6090.939090609126;6095.420935011167;6116.17431646722;6099.884870379854;6101.115338275925;6138.355472675394;6148.1699970503405;6204.627722336674;6141.762184092961;6168.9691551541955;6176.81469555914;6191.876162476725;6196.225471816023;6144.600600960924;6189.226346706661;6196.690165491797;6227.782027629011;6229.844253893689;4777.737224452689;4849.102915960488;4894;4901.264810278487;4957.107720610253;4386.561343865588;4394.758288294111;4424.380586717862;4452.821887124479;4472.709273897186;5637.943620563742;5644.238027866268;5647.29408823897;5650.576206784508;5659.207710920475;6135.171751813537;6147.907781383283;6185.288691800501;6235.781747638902;6266.780662676784;5608.775648974077;5632.831015069554;5644.915326270109;5653.858653533694;5690.544756419559;5316.441773613792;5331.226972089025;5335.62650614461;5345.33183352081;5356.384015838151;6152.138700582012;6191.535634827496;6236.126942228092;6250.656213908279;6291.245050593958;6220.34686357944;6262.68686565679;6277.435030847246;6290.308066112632;6294.244690637152;6278.309385967448;6282.371762823681;6285.120083188266;6288.371162883675;6298;6013.30846952606;6066.7269972850445;6112.2970858351855;6120.785772497941;6179.196704428396;6102.125716101218;6133.51829027926;6154.323024467394;6246.562740608114;6251.34360892118;5180.792768289377;6028.788992385245;6039.939600603938;6040.1543783871175;6044.546659000504;5959.195508606283;5959.28488581373;5995.760169593167;6006.129111278928;6029.879402412015;5751.281089863767;5841.094630332305;5852.824415267549;5853.560982926383;5857.238558987305;5776.566757493289;5779.884402311846;5805.41945805433;6035.879282414415;6133.723982420891;5782.017057100219;5870.47165755067;5960.374160713236;5961.046232602763;5977.880442391215 14:29:26 INFO - PROCESS | 1673 | |1;ext.html;488.5627363509659;503.3858692395278;509.20818127811236;592.0172513627379;620.7175735040557;30805.845970770144;31603.367932641333;37626.62373376269;38756.22487550247;39148;43424;43750.562494375044;43838.56161438385;43993.12013759723;44011.11977760443;9427.858582121291;9683.612655493771;9684.51577421126;9694.17599504043;9697.224222062254;7291.598962057111;8797.296216302713;11278.097752179847;11612.767744645102;11626.244294120897;11621.883781162207;11765.823512647274;11767.94116029423;11773.175877688565;11776.470058847372;8525.57372131391;11360.375179365132;11546.826797598002;11689.240199387013;11692.94153529227;11353.886461135447;11613.245139065933;11629.302241865431;11636.185467017312;11646.242994205435;40052.59947400547;40609;40663.39004914929;40681.779546613354;40690.59309406898;3175.4601717708047;3359.160209947513;3369.107186595554;3398.9490157647656;3735;28459;29078.12765617035;29092.41815163706;29408.264793380124;30115.548266776015;2677.8928842846262;2745.369931615147;2756.572731226643;2769.2799872033215;2815.6339675842214;483899;484525;485768.57115714194;486101;486192;34587.82706086453;58823;59431.702841485516;59701;59876;34668.826655867066;34874.47688284678;34916.82541587327;34952.475712864325;34953.126171845906;14022.579322620337;14239.928800355932;14250.928745356206;14254.786178207336;14257.857421425653;10888.727781805359;11269.66191014271;11827.645170644895;11915.523379064914;12049.518019279307;7975.481593696391;7990.440669153103;8003.359731221488;8085.716999905093;8101.6354264058255;3335.466325387975;3366.0911553880806;3368.5789276340456;3377.847996840148;3381.1716129547926;910.7358865928938;919.4621146629297;975.7999610079917;1059.8463222832645;1110.6612483192541;29047;29699.554506682416;29802.701972980427;29977;30168.396632067237;1088.4285749981323;1127.9041281491166;1134.5405110930087;1143.845580846575;1184.2953442701578;12830.935845320713;14094.788578171336;14255.572332830032;14460.276986150651;14543.927280363741;627785.8610706917;637544;643840;645838;649160;49102;57314.7134264326;58063;58087.4191258082;58096.70951645299;1309.803529470568;1426.992865035689;1462.6782107936237;1468.8751456126136;1472.124086168728;27902.72097279001;27914;28502.85748571244;28640;28720.569191462564;45970.850728730715;48782.512174879215;50200.748996254784;50341.49658503368;50402.495975041245;495812;516534;517164;517722;518269;22725.659115113616;25142.24573262805;25600.871995639904;25729.871350643127;26046.73953260519;41786.95532611709;41964.37053444262;42972.57027429686;43289.567104328555;44025.157232703736;34742.65257347394;35880.820595897894;36337.81831090934;36575.817120914224;37281.6271837289;34897.65102348944;35064.82467587646;35147.472787908715;36023.459648105825;36037.91886243417;35456;35964.921052368474;36063;36434.08914777152;36451.45322820213;29400.852995734884;30821.383572328876;30840;30968.845155774077;31074;23600.409989750395;23821.880890595436;23842.761572384054;23859.045638174277;24042 14:29:26 INFO - PROCESS | 1673 | |2;jquery.html;395.685430083084;397.4733478141462;398.98803035908935;404.44590910452683;410.0691430452872;19200.807991920075;19466.221351145956;20169.19323227073;20235.696464553006;20243.797562024374;20135.899320503377;20562.38312850613;21387.465313367175;21402.25092121776;21411.57176856462;4600.2869555218995;5311.681299122065;5316.202569614569;5318.414974352818;5334.893302133955;5070.163423035195;5241.685498870061;5324.973375133119;5381;5393.514583687467;5333.626646134772;5352.91970620441;5366.382865970405;5375.462453754612;5377.4084850666395;5365.980463711901;5372.516473517383;5568.415316391761;5866.530677545786;5874.471297583238;5491.780328786844;5661.547076233891;5674.460926212003;5675.2054712340305;5892.410758924116;13225.206487610774;13452.663683407894;13482.988775841852;13489.258090805002;18996.33512827058;2088.4778805298674;2134.9858817061927;2177.5536015116863;2213.9335819925427;2427.757224277576;9639.373440726331;9650.324477286593;9983.351082179708;9997.15024222942;10047.849282260771;1666.4917200253917;1733.7832770903638;1737.2453821640108;1798.183834327339;1965.1156979359346;113062.43468782604;117380;118294;119557.20664190044;122085;11277.661670149908;11576.884231157748;11622.128340374506;11632.127590430751;11806.704832379259;15111.395544178109;15138.091714497166;15154.696906062038;15154.848451515565;15161.772573411408;8322.16778322163;8513.063563008063;8515.361347898932;8602.31181505478;8638.78403039929;8299.792505187419;8331.50010999342;8336;8432.283255923192;8441.95779021101;3809.3904975203836;3882.514685664292;4491.820327186876;4559.521250268674;4645.86062418128;1814.8094450082815;1896.8198021187966;1962.4210857796984;1964.1848632817507;1997.131247907136;854.6752234150982;858.965641374338;919.1451949686722;1012.6607586458472;1055.714956961616;19225.711614325795;20433.48916277075;20490.385288441073;20625.17499300041;20745.481362966046;926.1479438916214;931.7484279244702;962.1052421248306;970.9514524273899;994.6817018554138;10483.266171367932;10830.350178989285;10855.945720271347;10918.945405272923;10956.28784129029;616843;618596;623421;629323;642694;130345;134103;135520.32239838934;136957;139155;921.709661456639;978.9167920726676;982.3025651787311;991.4893829677721;1034.7878684869434;15920.761188582412;16273.186340683154;16299;16333.509994700178;16622.91688541598;50449.556890147614;51213.48786512087;51215.48784512256;52465.21302180394;52982.20526692025;76698;77390;77813.44373112393;77877.61061194658;79196.93250613815;6654;7602.50583712057;7826.060872695313;8102.87845682327;8354.958225208835;11797.410129493663;12046.397680115784;12364.62906112818;12476.251424914533;12585.87414125847;10902.345859248471;11065.506156668747;11092.001719845253;11236.269642473213;11341.829872552084;10246.23153263523;10247.333923294962;10502.842457362993;10722.678319650424;10941.343519388862;9396.624135034523;10713.142948564253;11113.888861111285;11171.497282622464;11347.262427942158;7136.000959865519;7741.264579864904;7869.567173805267;8170.714025009096;8178.591070446573;9923.950380248052;10846.837297440703;11138.220324577525;11162.939520745516;11186.328820270484 14:29:26 INFO - PROCESS | 1673 | |3;mootools.html;1435.669795946933;1607.6543543138223;1612.7258366077767;1888.9905550472245;2012.0141130845882;7823.335016523595;8576.528290944003;8769.386142969995;8774.298056155509;8780.121987801216;8391.538465384401;8600.440971336875;8620.09489003655;8625.956870215656;8628.956855215714;2402.903883844644;2448.4735781806908;2449.0448724997264;2449.865257410842;2450.2281781238853;2406.927792166229;2427.320350301918;2446.0460420436043;2447.5472037673;2448.289995901195;2379.1435083369975;2390.247072172269;2406.7232268289195;2411.3368823573483;2413.348395933097;2389.2712722619594;2408.8675122868235;2409.2170044735444;2420.3586049696846;2421.806255499556;2378.750231225714;2403.9639405408934;2408.1089996701157;2411.2043025801445;2413.3001429585574;8434.451760635538;8542.74371768848;8676.305895528343;8677.958644962582;8692;5165.690058596496;5361.356637203521;5362.892742145135;5372.516473517363;5374.838754837361;3425.5270233799456;3453.4301840196335;3521.5774107107054;3543.167355671415;3557.484164796116;989.8465737810579;1016.4612755239734;1017.4759998600726;1028.8971102889652;1029.3720830293514;11288.266262692981;11408.657740267723;11417.828732569018;11461.255018423775;11468.942655286754;3725.5343082114737;4468.374427580141;4622.422197225347;4644.467325782483;4680.485146633868;9089.909100909039;9093.499857507768;9097.727068187965;9101.453912765255;9131.634734610674;4440.444944381952;5199.012187684328;5226.425093239816;5235.764390602432;5238.161894097002;4284.186004659167;4439.866803995886;4731.550502702238;4866.780994855169;4918.532739389752;3268.297316077055;3719.363480068761;3747.831347589365;3823.9617603824163;3857.1514266861245;1195.8684544700077;1207.221342234254;1208.812634041725;1217.792975194219;1223.7124275795181;652.8661624366994;777.3742137579189;928.7724507495572;933.9439633621869;942.7784470649392;5635.887282254414;5695.1741997410145;5711.600187986801;5768.134779783066;5897.410258974069;482.8961773218775;503.0718324690939;509.7374851951248;510.1276816643507;660.5409240577849;3742.812859357076;3895.844166233319;3955.4660120883887;4007.719459637856;4038.9596104038583;10127.341722787995;10564.683059508074;10579.894201058045;10590.576376944837;10590.894091059145;10548.314359566604;10596.046355827859;10617.362958222531;10633.394818269573;10638.680839574825;6696.363845434676;7604.5437273763755;7993.600319984093;8091.231333023471;8133;5765.509931655689;5859.5019423347785;5891.9116213255975;5896.734646940984;6090.512758979359;9380.765480863032;9717.708468745666;9818.067283608047;9822.852657210291;9836.704898853046;8284.633035549066;9143.268538517035;9798.971108033838;10024.694320306224;10026.598936042477;7206.56760594366;7287.198408174991;7386.778396648109;7411.740589079572;7417.406607471497;7930.088039875488;7954.204579542;8041.437099402986;8137.79655508617;8165.856780050916;7126.251743566851;7335.4498412618195;7395.852082958419;7420.998165247838;7453.180150183584;7085.256048114874;7156.212816590275;7164.9641751790905;7295.8176045597165;7316.085489313836;6932.168139823253;6975.895361569682;6997.405220556309;7000.929990700028;7018.192907815666;4698.624110071254;4855.490173531728;4873.610111191168;4906.63200259989;5039.974800125976;4812.738013133961;4844.006978569314;4914.8279810207905;4986.8753281168265;5139.434662187082 14:29:26 INFO - PROCESS | 1673 | |4;prototype.html;312.65607831385466;318.7895988647492;325.4564876655985;327.4204657755772;332.01226351604;5655.293088363955;5978.923793717129;5998.790042348519;6067.24159480065;6088.421599948009;5988.461038506533;6055.273367195931;6064.787732429366;6075.544334174943;6078.696065196745;3980.4825372701653;4120.917581648365;4510.909781804362;4540.387047748562;4546.749359067258;3808.2954653389074;4386.517483076859;4565.977170114144;4571.3828633134435;4572.702774319659;4467.508574056851;4473.552644735533;4494.910101797962;4497.595216430521;4507.639388848901;4480.372747815309;4500.954990450103;4531.796069176895;4533.569310915458;4542.500324964251;3372.2581032172884;3768.623137686237;3869.3808990561374;3878.8836334909997;3886.455896174538;5790.073588225906;5825.970870145665;5826.2134611827705;5861.2087368205175;5872.913511000478;4445.977770111129;5262.394824595144;5262.789488420459;5274;5294.894102117936;5539.833804985857;6090.786822461237;6375.649339286338;6447.838804029937;6449.06488559161;3638.909027274313;4282.593153650399;4309.267424537836;4311.353297005443;4330.935035974463;6396.200474940632;6454.935450645527;6775;6791.796246112624;6804.353586409283;4060.7563546187325;4380.540043295472;4420.524506276432;4424.889377765581;4437.889052773707;4454.487733910577;4455.353973673799;4478.708883922534;4487.730736155776;4497.617702495259;4711.646626502958;5017.247412888096;5253.4221235664045;5285.339332583427;5295.973520132375;5569.498745112959;6298.181236439325;6383.616982981036;6399.360063993563;6451.225852897683;1503.5188739603223;1538.4461593826213;1538.638419971306;1538.6614944712146;1543.3672194400242;991.3358050106302;1015.6035451254525;1029.912457441126;1030.7886883188928;1039.9792004159874;5883.970580147157;5928.229330187048;5958.970205148947;5972.880542389128;6024.337322894478;6555.868882622321;6576.572522786004;6594.73621055162;6601.207855057424;6622.9006564901565;5256.369235691742;5686.05176083064;5912.793052243151;5949.530944658005;5971.9701401493585;3458.152752575635;3461.7922924624604;3466.4867970721025;3488.755787094879;3532.31119931616;6510.479161667055;6662.459564614624;6856.314368563104;6865.382115609619;6883.449324054164;5575.526080283222;6031.095335699592;6747;6750.35871592198;6790.287019862942;6009.459148676553;6213.954959451707;6598.670066496752;6836.965815170893;6907.585544867324;6167.6916154193;6419.807405777834;6530.91010876534;6541.2477565078725;6546.607203567801;6325.936740632535;6457.870842583216;6469.223693156851;6492.870142597216;6572.882609956223;5219.32148820666;5679.886402271849;6433.678316084083;6899.379055884794;6962.756303529352;3821.675157611634;4268.402423660628;4276.615104640598;4317.136572685538;4377.715448495837;5387.730613469389;5846.532277417711;6590.462671871268;6637.2699003108655;6657.500687448364;6176.491161452075;6446.161998940108;6449.322821103904;6468.708908099053;6477.1579694639395;6393.520485963664;6399.304184391047;6422.518311126591;6426.742930282736;6438.774642887476;5801.82594522165;5900.64596124234;6274.780382686767;6294.5593808434805;6325.240971083499;5393.973030134981;6447.196154268816;6451.225852897683;6480.675966201577;6502.122213501117;6096.683260361035;6299.590526615755;6310.2743184534365;6332.398422149889;6340.71466784005 14:29:26 INFO - PROCESS | 1673 | |5;yui.html;667.4927055437865;689.4868205391349;697.3549466743261;722.1154086244351;782.7338704840355;14986.175760333177;15039.097654140733;15415.768763468543;15432.382704691812;15436.228188590581;13926;14112.15327080376;14173.936954728386;14183.929080354585;14209.076410033367;740.4594645908463;915.8168366326728;923.9491827949477;924.0805398628374;925.6158694141923;739.2828955912782;897.3270047464401;938.6949241496507;939.5912777941618;941.1153515695258;971.5093877591806;973.2068364283122;974.3715303629155;975.6195083917278;976.077606661705;749.8462815122941;939.6241503398627;965.6137544981993;967.2455484721893;967.6468089147481;725.3725527418798;725.9382952449048;726.0017475970541;728.9927100728978;734.3574372424129;15077.924610376878;15175.848241517553;15207.61980950474;15215.08709477435;15222.847771522365;12702;12800.423980920881;12821.871781282161;12827.166234194841;13082.934585327108;8721.60752766127;9593.328467007312;10029.247806414549;10128.797424051552;10283.434411107386;3478.1826270826336;3518.2259902821593;3563.8396272167815;3564.7326450516057;3577.6601222883783;86087.56956215242;86231;86452;86561.56719216426;88282.5585872073;9465.621375144918;9899.010098990044;9922.900770992197;10037.69886903394;10085.747856303651;23102.65346019811;23232.186873459348;23344.299671009896;23405.53188936212;23416.882915585313;7492.1384040836;7554.206808285051;7594.468387212842;7646.082470103624;7667.118281397599;3936.488256526633;3963.3262345400776;3976.0855003348815;3983.6813054955533;4103.876883693494;3616.87340943071;3693.593704692482;3695.5380577427823;3700.9814950925074;3706.2031663192543;659.7163219815429;662.3244290823333;689.582802404538;704.2007321689816;705.2277755857328;518.6758276077452;538.4238864415038;568.2045136808501;571.9256496655408;601.2304250559291;7901.407394445439;7969.521828690297;8072.717454889051;8242.752717418487;8536.359773017048;519.2237604780802;520.622548652224;529.1375058654446;542.2625229687621;596.7642781101528;3881.8059097045034;3938.606139386096;4109.527404348479;4124.835006599762;4234.13200293939;87433.56283218543;88843.11156888348;89679;90231;90979;75345;77584.61207693926;80193;81566;81921.3615727694;67839.66080169666;70007.64996174986;71345.64327178331;71820;72057.27942720505;6803.693833777393;8209.850620912957;8232.382571307056;8290.760910275925;8393.496390216607;44368;45077.64767056994;45250.86872828074;45312.54687453083;45340;64989.70020599656;65110.67444662746;65112.34887651063;65511;65877;10969.122470202525;11142.108631309313;11151.609693660683;11180.161487888277;11993.040556755614;10193.74515637115;11851;12078.21491603043;12372.381380931098;12498.75002499963;11387.487563059847;11466.31202127875;11582.957533821997;11815.940920295343;12036.63890083299;11259;11310.377929213972;11324.773504529698;11639.76720465569;11686.766264674829;11341.773164536828;11390.031847293072;11835.349055802013;11924.284542927453;12332.81500777507;9356.48539330316;9626.9037309626;9773.9022609773;9948.751281218027;10025.749356266151;9588.328816982745;9739.269554783561;9861.09459834337;10035.69892903214;10170.389776613427 14:29:26 INFO - PROCESS | 1673 | __end_tp_report 14:29:26 INFO - PROCESS | 1673 | __start_cc_report 14:29:26 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1916 14:29:26 INFO - PROCESS | 1673 | __end_cc_report 14:29:26 INFO - PROCESS | 1673 | __startTimestamp1461274166438__endTimestamp 14:29:26 INFO - PROCESS | 1673 | 14:29:26 INFO - PROCESS | 1673 | ------- Summary: start ------- 14:29:26 INFO - PROCESS | 1673 | Number of tests: 6 14:29:26 INFO - PROCESS | 1673 | 14:29:26 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5676.26 Median:5852.82 stddev:645.58 (11.0%) stddev-sans-first:611.09 14:29:26 INFO - PROCESS | 1673 | Values: 2949.4 3120.0 3386.2 3446.3 3480.4 5544.0 5597.8 5600.1 5607.5 5651.9 5557.9 5618.0 5650.3 5653.8 5667.7 5094.7 5110.1 5148.8 5168.4 5197.3 5172.2 5199.5 5200.2 5206.8 5214.9 5120.0 5170.3 5174.5 5183.4 5253.7 5193.7 5202.7 5213.5 5214.8 5271.3 5020.8 5052.7 5110.2 5114.1 5139.8 4665.4 5509.0 5552.6 5563.5 5577.0 6130.0 6152.4 6158.8 6172.8 6176.8 6254.5 6257.4 6258.7 6261.7 6264.3 5563.6 5581.0 5636.4 5653.8 5674.2 6253.9 6258.2 6275.4 6282.7 6291.7 6089.0 6090.1 6090.9 6095.4 6116.2 6099.9 6101.1 6138.4 6148.2 6204.6 6141.8 6169.0 6176.8 6191.9 6196.2 6144.6 6189.2 6196.7 6227.8 6229.8 4777.7 4849.1 4894.0 4901.3 4957.1 4386.6 4394.8 4424.4 4452.8 4472.7 5637.9 5644.2 5647.3 5650.6 5659.2 6135.2 6147.9 6185.3 6235.8 6266.8 5608.8 5632.8 5644.9 5653.9 5690.5 5316.4 5331.2 5335.6 5345.3 5356.4 6152.1 6191.5 6236.1 6250.7 6291.2 6220.3 6262.7 6277.4 6290.3 6294.2 6278.3 6282.4 6285.1 6288.4 6298.0 6013.3 6066.7 6112.3 6120.8 6179.2 6102.1 6133.5 6154.3 6246.6 6251.3 5180.8 6028.8 6039.9 6040.2 6044.5 5959.2 5959.3 5995.8 6006.1 6029.9 5751.3 5841.1 5852.8 5853.6 5857.2 5776.6 5779.9 5805.4 6035.9 6133.7 5782.0 5870.5 5960.4 5961.0 5977.9 14:29:26 INFO - PROCESS | 1673 | 14:29:26 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:66294.74 Median:34628.33 stddev:147386.37 (425.6%) stddev-sans-first:147717.01 14:29:26 INFO - PROCESS | 1673 | Values: 488.6 503.4 509.2 592.0 620.7 30805.8 31603.4 37626.6 38756.2 39148.0 43424.0 43750.6 43838.6 43993.1 44011.1 9427.9 9683.6 9684.5 9694.2 9697.2 7291.6 8797.3 11278.1 11612.8 11626.2 11621.9 11765.8 11767.9 11773.2 11776.5 8525.6 11360.4 11546.8 11689.2 11692.9 11353.9 11613.2 11629.3 11636.2 11646.2 40052.6 40609.0 40663.4 40681.8 40690.6 3175.5 3359.2 3369.1 3398.9 3735.0 28459.0 29078.1 29092.4 29408.3 30115.5 2677.9 2745.4 2756.6 2769.3 2815.6 483899.0 484525.0 485768.6 486101.0 486192.0 34587.8 58823.0 59431.7 59701.0 59876.0 34668.8 34874.5 34916.8 34952.5 34953.1 14022.6 14239.9 14250.9 14254.8 14257.9 10888.7 11269.7 11827.6 11915.5 12049.5 7975.5 7990.4 8003.4 8085.7 8101.6 3335.5 3366.1 3368.6 3377.8 3381.2 910.7 919.5 975.8 1059.8 1110.7 29047.0 29699.6 29802.7 29977.0 30168.4 1088.4 1127.9 1134.5 1143.8 1184.3 12830.9 14094.8 14255.6 14460.3 14543.9 627785.9 637544.0 643840.0 645838.0 649160.0 49102.0 57314.7 58063.0 58087.4 58096.7 1309.8 1427.0 1462.7 1468.9 1472.1 27902.7 27914.0 28502.9 28640.0 28720.6 45970.9 48782.5 50200.7 50341.5 50402.5 495812.0 516534.0 517164.0 517722.0 518269.0 22725.7 25142.2 25600.9 25729.9 26046.7 41787.0 41964.4 42972.6 43289.6 44025.2 34742.7 35880.8 36337.8 36575.8 37281.6 34897.7 35064.8 35147.5 36023.5 36037.9 35456.0 35964.9 36063.0 36434.1 36451.5 29400.9 30821.4 30840.0 30968.8 31074.0 23600.4 23821.9 23842.8 23859.0 24042.0 14:29:26 INFO - PROCESS | 1673 | 14:29:26 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:35466.78 Median:2320.85 stddev:104538.67 (4504.3%) stddev-sans-first:104798.76 14:29:26 INFO - PROCESS | 1673 | Values: 395.7 397.5 399.0 404.4 410.1 19200.8 19466.2 20169.2 20235.7 20243.8 20135.9 20562.4 21387.5 21402.3 21411.6 4600.3 5311.7 5316.2 5318.4 5334.9 5070.2 5241.7 5325.0 5381.0 5393.5 5333.6 5352.9 5366.4 5375.5 5377.4 5366.0 5372.5 5568.4 5866.5 5874.5 5491.8 5661.5 5674.5 5675.2 5892.4 13225.2 13452.7 13483.0 13489.3 18996.3 2088.5 2135.0 2177.6 2213.9 2427.8 9639.4 9650.3 9983.4 9997.2 10047.8 1666.5 1733.8 1737.2 1798.2 1965.1 113062.4 117380.0 118294.0 119557.2 122085.0 11277.7 11576.9 11622.1 11632.1 11806.7 15111.4 15138.1 15154.7 15154.8 15161.8 8322.2 8513.1 8515.4 8602.3 8638.8 8299.8 8331.5 8336.0 8432.3 8442.0 3809.4 3882.5 4491.8 4559.5 4645.9 1814.8 1896.8 1962.4 1964.2 1997.1 854.7 859.0 919.1 1012.7 1055.7 19225.7 20433.5 20490.4 20625.2 20745.5 926.1 931.7 962.1 971.0 994.7 10483.3 10830.4 10855.9 10918.9 10956.3 616843.0 618596.0 623421.0 629323.0 642694.0 130345.0 134103.0 135520.3 136957.0 139155.0 921.7 978.9 982.3 991.5 1034.8 15920.8 16273.2 16299.0 16333.5 16622.9 50449.6 51213.5 51215.5 52465.2 52982.2 76698.0 77390.0 77813.4 77877.6 79196.9 6654.0 7602.5 7826.1 8102.9 8355.0 11797.4 12046.4 12364.6 12476.3 12585.9 10902.3 11065.5 11092.0 11236.3 11341.8 10246.2 10247.3 10502.8 10722.7 10941.3 9396.6 10713.1 11113.9 11171.5 11347.3 7136.0 7741.3 7869.6 8170.7 8178.6 9924.0 10846.8 11138.2 11162.9 11186.3 14:29:26 INFO - PROCESS | 1673 | 14:29:26 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:5571.59 Median:2744.97 stddev:3102.68 (113.0%) stddev-sans-first:3095.82 14:29:26 INFO - PROCESS | 1673 | Values: 1435.7 1607.7 1612.7 1889.0 2012.0 7823.3 8576.5 8769.4 8774.3 8780.1 8391.5 8600.4 8620.1 8626.0 8629.0 2402.9 2448.5 2449.0 2449.9 2450.2 2406.9 2427.3 2446.0 2447.5 2448.3 2379.1 2390.2 2406.7 2411.3 2413.3 2389.3 2408.9 2409.2 2420.4 2421.8 2378.8 2404.0 2408.1 2411.2 2413.3 8434.5 8542.7 8676.3 8678.0 8692.0 5165.7 5361.4 5362.9 5372.5 5374.8 3425.5 3453.4 3521.6 3543.2 3557.5 989.8 1016.5 1017.5 1028.9 1029.4 11288.3 11408.7 11417.8 11461.3 11468.9 3725.5 4468.4 4622.4 4644.5 4680.5 9089.9 9093.5 9097.7 9101.5 9131.6 4440.4 5199.0 5226.4 5235.8 5238.2 4284.2 4439.9 4731.6 4866.8 4918.5 3268.3 3719.4 3747.8 3824.0 3857.2 1195.9 1207.2 1208.8 1217.8 1223.7 652.9 777.4 928.8 933.9 942.8 5635.9 5695.2 5711.6 5768.1 5897.4 482.9 503.1 509.7 510.1 660.5 3742.8 3895.8 3955.5 4007.7 4039.0 10127.3 10564.7 10579.9 10590.6 10590.9 10548.3 10596.0 10617.4 10633.4 10638.7 6696.4 7604.5 7993.6 8091.2 8133.0 5765.5 5859.5 5891.9 5896.7 6090.5 9380.8 9717.7 9818.1 9822.9 9836.7 8284.6 9143.3 9799.0 10024.7 10026.6 7206.6 7287.2 7386.8 7411.7 7417.4 7930.1 7954.2 8041.4 8137.8 8165.9 7126.3 7335.4 7395.9 7421.0 7453.2 7085.3 7156.2 7165.0 7295.8 7316.1 6932.2 6975.9 6997.4 7000.9 7018.2 4698.6 4855.5 4873.6 4906.6 5040.0 4812.7 4844.0 4914.8 4986.9 5139.4 14:29:26 INFO - PROCESS | 1673 | 14:29:26 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:5192.90 Median:5878.44 stddev:1612.70 (27.4%) stddev-sans-first:1575.07 14:29:26 INFO - PROCESS | 1673 | Values: 312.7 318.8 325.5 327.4 332.0 5655.3 5978.9 5998.8 6067.2 6088.4 5988.5 6055.3 6064.8 6075.5 6078.7 3980.5 4120.9 4510.9 4540.4 4546.7 3808.3 4386.5 4566.0 4571.4 4572.7 4467.5 4473.6 4494.9 4497.6 4507.6 4480.4 4501.0 4531.8 4533.6 4542.5 3372.3 3768.6 3869.4 3878.9 3886.5 5790.1 5826.0 5826.2 5861.2 5872.9 4446.0 5262.4 5262.8 5274.0 5294.9 5539.8 6090.8 6375.6 6447.8 6449.1 3638.9 4282.6 4309.3 4311.4 4330.9 6396.2 6454.9 6775.0 6791.8 6804.4 4060.8 4380.5 4420.5 4424.9 4437.9 4454.5 4455.4 4478.7 4487.7 4497.6 4711.6 5017.2 5253.4 5285.3 5296.0 5569.5 6298.2 6383.6 6399.4 6451.2 1503.5 1538.4 1538.6 1538.7 1543.4 991.3 1015.6 1029.9 1030.8 1040.0 5884.0 5928.2 5959.0 5972.9 6024.3 6555.9 6576.6 6594.7 6601.2 6622.9 5256.4 5686.1 5912.8 5949.5 5972.0 3458.2 3461.8 3466.5 3488.8 3532.3 6510.5 6662.5 6856.3 6865.4 6883.4 5575.5 6031.1 6747.0 6750.4 6790.3 6009.5 6214.0 6598.7 6837.0 6907.6 6167.7 6419.8 6530.9 6541.2 6546.6 6325.9 6457.9 6469.2 6492.9 6572.9 5219.3 5679.9 6433.7 6899.4 6962.8 3821.7 4268.4 4276.6 4317.1 4377.7 5387.7 5846.5 6590.5 6637.3 6657.5 6176.5 6446.2 6449.3 6468.7 6477.2 6393.5 6399.3 6422.5 6426.7 6438.8 5801.8 5900.6 6274.8 6294.6 6325.2 5394.0 6447.2 6451.2 6480.7 6502.1 6096.7 6299.6 6310.3 6332.4 6340.7 14:29:26 INFO - PROCESS | 1673 | 14:29:26 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:18341.49 Median:584.34 stddev:25717.36 (4401.1%) stddev-sans-first:25755.26 14:29:26 INFO - PROCESS | 1673 | Values: 667.5 689.5 697.4 722.1 782.7 14986.2 15039.1 15415.8 15432.4 15436.2 13926.0 14112.2 14173.9 14183.9 14209.1 740.5 915.8 923.9 924.1 925.6 739.3 897.3 938.7 939.6 941.1 971.5 973.2 974.4 975.6 976.1 749.8 939.6 965.6 967.2 967.6 725.4 725.9 726.0 729.0 734.4 15077.9 15175.8 15207.6 15215.1 15222.8 12702.0 12800.4 12821.9 12827.2 13082.9 8721.6 9593.3 10029.2 10128.8 10283.4 3478.2 3518.2 3563.8 3564.7 3577.7 86087.6 86231.0 86452.0 86561.6 88282.6 9465.6 9899.0 9922.9 10037.7 10085.7 23102.7 23232.2 23344.3 23405.5 23416.9 7492.1 7554.2 7594.5 7646.1 7667.1 3936.5 3963.3 3976.1 3983.7 4103.9 3616.9 3693.6 3695.5 3701.0 3706.2 659.7 662.3 689.6 704.2 705.2 518.7 538.4 568.2 571.9 601.2 7901.4 7969.5 8072.7 8242.8 8536.4 519.2 520.6 529.1 542.3 596.8 3881.8 3938.6 4109.5 4124.8 4234.1 87433.6 88843.1 89679.0 90231.0 90979.0 75345.0 77584.6 80193.0 81566.0 81921.4 67839.7 70007.6 71345.6 71820.0 72057.3 6803.7 8209.9 8232.4 8290.8 8393.5 44368.0 45077.6 45250.9 45312.5 45340.0 64989.7 65110.7 65112.3 65511.0 65877.0 10969.1 11142.1 11151.6 11180.2 11993.0 10193.7 11851.0 12078.2 12372.4 12498.8 11387.5 11466.3 11583.0 11815.9 12036.6 11259.0 11310.4 11324.8 11639.8 11686.8 11341.8 11390.0 11835.3 11924.3 12332.8 9356.5 9626.9 9773.9 9948.8 10025.7 9588.3 9739.3 9861.1 10035.7 10170.4 14:29:26 INFO - PROCESS | 1673 | -------- Summary: end -------- 14:29:26 INFO - PROCESS | 1673 | 14:29:27 INFO - TEST-INFO | 1673: exit 0 14:29:27 INFO - TEST-OK | dromaeo_css | took 1116292ms 14:29:27 INFO - TEST-START | kraken 14:29:27 INFO - operating with platform_type : mac_ 14:29:27 INFO - Initialising browser for kraken test... 14:29:27 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpZ73iQp/profile http://localhost:49275/getInfo.html) 14:29:29 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 14:29:29 INFO - PROCESS | 1678 | colorDepth:24 14:29:29 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 14:29:29 INFO - PROCESS | 1678 | __metrics 14:29:29 INFO - PROCESS | 1678 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:29:29 ERROR - PROCESS | 1678 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:29:29 INFO - TEST-INFO | 1678: exit 0 14:29:29 INFO - Browser initialized. 14:29:29 INFO - Running cycle 1/1 for kraken test... 14:29:29 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpZ73iQp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:29:41 INFO - PROCESS | 1680 | RSS: Main: 238657536 14:29:41 INFO - PROCESS | 1680 | 14:29:44 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 14:29:45 INFO - PROCESS | 1680 | RSS: Main: 286318592 14:29:45 INFO - PROCESS | 1680 | 14:29:48 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 14:29:48 INFO - PROCESS | 1680 | RSS: Main: 298950656 14:29:48 INFO - PROCESS | 1680 | 14:29:52 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 14:29:52 INFO - PROCESS | 1680 | RSS: Main: 384921600 14:29:52 INFO - PROCESS | 1680 | 14:29:54 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 14:29:55 INFO - PROCESS | 1680 | RSS: Main: 285016064 14:29:55 INFO - PROCESS | 1680 | 14:29:57 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:29:58 INFO - PROCESS | 1680 | RSS: Main: 290795520 14:29:58 INFO - PROCESS | 1680 | 14:30:00 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 14:30:01 INFO - PROCESS | 1680 | RSS: Main: 327651328 14:30:01 INFO - PROCESS | 1680 | 14:30:04 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 14:30:04 INFO - PROCESS | 1680 | RSS: Main: 328220672 14:30:04 INFO - PROCESS | 1680 | 14:30:07 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 14:30:08 INFO - PROCESS | 1680 | RSS: Main: 328470528 14:30:08 INFO - PROCESS | 1680 | 14:30:09 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:30:10 INFO - PROCESS | 1680 | RSS: Main: 319639552 14:30:10 INFO - PROCESS | 1680 | 14:30:12 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:30:13 INFO - PROCESS | 1680 | RSS: Main: 326553600 14:30:13 INFO - PROCESS | 1680 | 14:30:15 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:30:15 INFO - PROCESS | 1680 | RSS: Main: 329904128 14:30:15 INFO - PROCESS | 1680 | 14:30:18 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:30:19 INFO - PROCESS | 1680 | RSS: Main: 330829824 14:30:19 INFO - PROCESS | 1680 | 14:30:22 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:30:23 INFO - PROCESS | 1680 | RSS: Main: 326361088 14:30:23 INFO - PROCESS | 1680 | 14:30:24 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 14:30:25 INFO - PROCESS | 1680 | RSS: Main: 391376896 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | __start_tp_report 14:30:25 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 14:30:25 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 14:30:25 INFO - PROCESS | 1680 | |i|pagename|runs| 14:30:25 INFO - PROCESS | 1680 | |0;ai-astar;112;110;113;112;113;112;121;111;112;113 14:30:25 INFO - PROCESS | 1680 | |1;audio-beat-detection;158;121;147;201;148;143;139;121;172;121 14:30:25 INFO - PROCESS | 1680 | |2;audio-dft;186;186;185;184;186;183;182;186;185;195 14:30:25 INFO - PROCESS | 1680 | |3;audio-fft;78;74;84;91;57;57;58;80;85;55 14:30:25 INFO - PROCESS | 1680 | |4;audio-oscillator;88;102;135;82;79;78;129;80;78;123 14:30:25 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;88;83;87;82;87;85;87;84;88;84 14:30:25 INFO - PROCESS | 1680 | |6;imaging-darkroom;92;95;94;93;92;94;94;93;93;114 14:30:25 INFO - PROCESS | 1680 | |7;imaging-desaturate;99;143;85;84;84;86;84;86;86;85 14:30:25 INFO - PROCESS | 1680 | |8;json-parse-financial;58;59;59;58;58;59;57;80;58;55 14:30:25 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;63;70;65;78;69;71;65;91;97;97 14:30:25 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;82;81;80;82;81;80;81;80;84;84 14:30:25 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;139;141;140;140;138;138;157;153;193;144 14:30:25 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;166;204;220;194;168;167;169;170;173;169 14:30:25 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;68;68;68;70;70;70;87;97;93;92 14:30:25 INFO - PROCESS | 1680 | __end_tp_report 14:30:25 INFO - PROCESS | 1680 | __start_cc_report 14:30:25 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1937 14:30:25 INFO - PROCESS | 1680 | __end_cc_report 14:30:25 INFO - PROCESS | 1680 | __startTimestamp1461274225165__endTimestamp 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | ------- Summary: start ------- 14:30:25 INFO - PROCESS | 1680 | Number of tests: 14 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:112.90 Median:112.50 stddev:3.00 (2.7%) stddev-sans-first:3.16 14:30:25 INFO - PROCESS | 1680 | Values: 112.0 110.0 113.0 112.0 113.0 112.0 121.0 111.0 112.0 113.0 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:147.10 Median:147.50 stddev:25.32 (17.2%) stddev-sans-first:26.55 14:30:25 INFO - PROCESS | 1680 | Values: 158.0 121.0 147.0 201.0 148.0 143.0 139.0 121.0 172.0 121.0 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:185.80 Median:186.00 stddev:3.52 (1.9%) stddev-sans-first:3.73 14:30:25 INFO - PROCESS | 1680 | Values: 186.0 186.0 185.0 184.0 186.0 183.0 182.0 186.0 185.0 195.0 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:71.90 Median:79.00 stddev:13.80 (17.5%) stddev-sans-first:14.45 14:30:25 INFO - PROCESS | 1680 | Values: 78.0 74.0 84.0 91.0 57.0 57.0 58.0 80.0 85.0 55.0 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:78.50 stddev:23.11 (29.4%) stddev-sans-first:24.26 14:30:25 INFO - PROCESS | 1680 | Values: 88.0 102.0 135.0 82.0 79.0 78.0 129.0 80.0 78.0 123.0 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:87.00 stddev:2.17 (2.5%) stddev-sans-first:2.11 14:30:25 INFO - PROCESS | 1680 | Values: 88.0 83.0 87.0 82.0 87.0 85.0 87.0 84.0 88.0 84.0 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:93.50 stddev:6.60 (7.1%) stddev-sans-first:6.89 14:30:25 INFO - PROCESS | 1680 | Values: 92.0 95.0 94.0 93.0 92.0 94.0 94.0 93.0 93.0 114.0 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:92.20 Median:85.50 stddev:18.40 (21.5%) stddev-sans-first:19.35 14:30:25 INFO - PROCESS | 1680 | Values: 99.0 143.0 85.0 84.0 84.0 86.0 84.0 86.0 86.0 85.0 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:58.50 stddev:7.09 (12.1%) stddev-sans-first:7.48 14:30:25 INFO - PROCESS | 1680 | Values: 58.0 59.0 59.0 58.0 58.0 59.0 57.0 80.0 58.0 55.0 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:76.60 Median:74.50 stddev:13.45 (18.1%) stddev-sans-first:13.34 14:30:25 INFO - PROCESS | 1680 | Values: 63.0 70.0 65.0 78.0 69.0 71.0 65.0 91.0 97.0 97.0 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:81.50 stddev:1.51 (1.9%) stddev-sans-first:1.59 14:30:25 INFO - PROCESS | 1680 | Values: 82.0 81.0 80.0 82.0 81.0 80.0 81.0 80.0 84.0 84.0 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:142.50 stddev:17.01 (11.9%) stddev-sans-first:17.71 14:30:25 INFO - PROCESS | 1680 | Values: 139.0 141.0 140.0 140.0 138.0 138.0 157.0 153.0 193.0 144.0 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.00 Median:171.50 stddev:19.07 (11.1%) stddev-sans-first:19.54 14:30:25 INFO - PROCESS | 1680 | Values: 166.0 204.0 220.0 194.0 168.0 167.0 169.0 170.0 173.0 169.0 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.30 Median:78.50 stddev:12.27 (15.6%) stddev-sans-first:12.43 14:30:25 INFO - PROCESS | 1680 | Values: 68.0 68.0 68.0 70.0 70.0 70.0 87.0 97.0 93.0 92.0 14:30:25 INFO - PROCESS | 1680 | -------- Summary: end -------- 14:30:25 INFO - PROCESS | 1680 | 14:30:25 INFO - TEST-INFO | 1680: exit 0 14:30:26 INFO - TEST-OK | kraken | took 58973ms 14:30:26 INFO - SUITE-END | took 1175s 14:30:26 INFO - Completed test suite (00:19:35) 14:30:26 INFO - javascript benchmark 14:30:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2949.39537394834, 3119.9688003119977, 3386.187315044387, 3446.276281980785, 3480.3909315869732, 5543.974364742526, 5597.804076857311, 5600.0759874620635, 5607.523360514356, 5651.9152212716845, 5557.944420555793, 5618.0449323614985, 5650.321961364642, 5653.830385088454, 5667.716614169285, 5094.719790411516, 5110.1312776827845, 5148.768305426265, 5168.3539557555305, 5197.298364720771, 5172.172452407615, 5199.454057323983, 5200.245964335169, 5206.7656955436905, 5214.921776173361, 5120.001599688062, 5170.327857378536, 5174.534291913727, 5183.429822719498, 5253.7373131343475, 5193.714345710965, 5202.739863006835, 5213.478652134756, 5214.765335559871, 5271.28837606922, 5020.7740651670765, 5052.722100284483, 5110.233464980282, 5114.130597798383, 5139.794408223666, 4665.4168228971375, 5509.0, 5552.611317207797, 5563.527100196488, 5576.972115139439, 6130.0, 6152.3540028297275, 6158.84602884927, 6172.814815555495, 6176.783812566584, 6254.499640028786, 6257.436830685215, 6258.749650013994, 6261.749530018794, 6264.3422440643535, 5563.582731295169, 5580.972095139539, 5636.379998200195, 5653.802116925888, 5674.177244299594, 6253.937460625427, 6258.249010118815, 6275.435210831047, 6282.748690052346, 6291.653959032205, 6089.0, 6090.056041313604, 6090.939090609126, 6095.420935011167, 6116.17431646722, 6099.884870379854, 6101.115338275925, 6138.355472675394, 6148.1699970503405, 6204.627722336674, 6141.762184092961, 6168.9691551541955, 6176.81469555914, 6191.876162476725, 6196.225471816023, 6144.600600960924, 6189.226346706661, 6196.690165491797, 6227.782027629011, 6229.844253893689, 4777.737224452689, 4849.102915960488, 4894.0, 4901.264810278487, 4957.107720610253, 4386.561343865588, 4394.758288294111, 4424.380586717862, 4452.821887124479, 4472.709273897186, 5637.943620563742, 5644.238027866268, 5647.29408823897, 5650.576206784508, 5659.207710920475, 6135.171751813537, 6147.907781383283, 6185.288691800501, 6235.781747638902, 6266.780662676784, 5608.775648974077, 5632.831015069554, 5644.915326270109, 5653.858653533694, 5690.544756419559, 5316.441773613792, 5331.226972089025, 5335.62650614461, 5345.33183352081, 5356.384015838151, 6152.138700582012, 6191.535634827496, 6236.126942228092, 6250.656213908279, 6291.245050593958, 6220.34686357944, 6262.68686565679, 6277.435030847246, 6290.308066112632, 6294.244690637152, 6278.309385967448, 6282.371762823681, 6285.120083188266, 6288.371162883675, 6298.0, 6013.30846952606, 6066.7269972850445, 6112.2970858351855, 6120.785772497941, 6179.196704428396, 6102.125716101218, 6133.51829027926, 6154.323024467394, 6246.562740608114, 6251.34360892118, 5180.792768289377, 6028.788992385245, 6039.939600603938, 6040.1543783871175, 6044.546659000504, 5959.195508606283, 5959.28488581373, 5995.760169593167, 6006.129111278928, 6029.879402412015, 5751.281089863767, 5841.094630332305, 5852.824415267549, 5853.560982926383, 5857.238558987305, 5776.566757493289, 5779.884402311846, 5805.41945805433, 6035.879282414415, 6133.723982420891, 5782.017057100219, 5870.47165755067, 5960.374160713236, 5961.046232602763, 5977.880442391215], "value": 5633.849725483452, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.5627363509659, 503.3858692395278, 509.20818127811236, 592.0172513627379, 620.7175735040557, 30805.845970770144, 31603.367932641333, 37626.62373376269, 38756.22487550247, 39148.0, 43424.0, 43750.562494375044, 43838.56161438385, 43993.12013759723, 44011.11977760443, 9427.858582121291, 9683.612655493771, 9684.51577421126, 9694.17599504043, 9697.224222062254, 7291.598962057111, 8797.296216302713, 11278.097752179847, 11612.767744645102, 11626.244294120897, 11621.883781162207, 11765.823512647274, 11767.94116029423, 11773.175877688565, 11776.470058847372, 8525.57372131391, 11360.375179365132, 11546.826797598002, 11689.240199387013, 11692.94153529227, 11353.886461135447, 11613.245139065933, 11629.302241865431, 11636.185467017312, 11646.242994205435, 40052.59947400547, 40609.0, 40663.39004914929, 40681.779546613354, 40690.59309406898, 3175.4601717708047, 3359.160209947513, 3369.107186595554, 3398.9490157647656, 3735.0, 28459.0, 29078.12765617035, 29092.41815163706, 29408.264793380124, 30115.548266776015, 2677.8928842846262, 2745.369931615147, 2756.572731226643, 2769.2799872033215, 2815.6339675842214, 483899.0, 484525.0, 485768.57115714194, 486101.0, 486192.0, 34587.82706086453, 58823.0, 59431.702841485516, 59701.0, 59876.0, 34668.826655867066, 34874.47688284678, 34916.82541587327, 34952.475712864325, 34953.126171845906, 14022.579322620337, 14239.928800355932, 14250.928745356206, 14254.786178207336, 14257.857421425653, 10888.727781805359, 11269.66191014271, 11827.645170644895, 11915.523379064914, 12049.518019279307, 7975.481593696391, 7990.440669153103, 8003.359731221488, 8085.716999905093, 8101.6354264058255, 3335.466325387975, 3366.0911553880806, 3368.5789276340456, 3377.847996840148, 3381.1716129547926, 910.7358865928938, 919.4621146629297, 975.7999610079917, 1059.8463222832645, 1110.6612483192541, 29047.0, 29699.554506682416, 29802.701972980427, 29977.0, 30168.396632067237, 1088.4285749981323, 1127.9041281491166, 1134.5405110930087, 1143.845580846575, 1184.2953442701578, 12830.935845320713, 14094.788578171336, 14255.572332830032, 14460.276986150651, 14543.927280363741, 627785.8610706917, 637544.0, 643840.0, 645838.0, 649160.0, 49102.0, 57314.7134264326, 58063.0, 58087.4191258082, 58096.70951645299, 1309.803529470568, 1426.992865035689, 1462.6782107936237, 1468.8751456126136, 1472.124086168728, 27902.72097279001, 27914.0, 28502.85748571244, 28640.0, 28720.569191462564, 45970.850728730715, 48782.512174879215, 50200.748996254784, 50341.49658503368, 50402.495975041245, 495812.0, 516534.0, 517164.0, 517722.0, 518269.0, 22725.659115113616, 25142.24573262805, 25600.871995639904, 25729.871350643127, 26046.73953260519, 41786.95532611709, 41964.37053444262, 42972.57027429686, 43289.567104328555, 44025.157232703736, 34742.65257347394, 35880.820595897894, 36337.81831090934, 36575.817120914224, 37281.6271837289, 34897.65102348944, 35064.82467587646, 35147.472787908715, 36023.459648105825, 36037.91886243417, 35456.0, 35964.921052368474, 36063.0, 36434.08914777152, 36451.45322820213, 29400.852995734884, 30821.383572328876, 30840.0, 30968.845155774077, 31074.0, 23600.409989750395, 23821.880890595436, 23842.761572384054, 23859.045638174277, 24042.0], "value": 18417.107777806374, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.685430083084, 397.4733478141462, 398.98803035908935, 404.44590910452683, 410.0691430452872, 19200.807991920075, 19466.221351145956, 20169.19323227073, 20235.696464553006, 20243.797562024374, 20135.899320503377, 20562.38312850613, 21387.465313367175, 21402.25092121776, 21411.57176856462, 4600.2869555218995, 5311.681299122065, 5316.202569614569, 5318.414974352818, 5334.893302133955, 5070.163423035195, 5241.685498870061, 5324.973375133119, 5381.0, 5393.514583687467, 5333.626646134772, 5352.91970620441, 5366.382865970405, 5375.462453754612, 5377.4084850666395, 5365.980463711901, 5372.516473517383, 5568.415316391761, 5866.530677545786, 5874.471297583238, 5491.780328786844, 5661.547076233891, 5674.460926212003, 5675.2054712340305, 5892.410758924116, 13225.206487610774, 13452.663683407894, 13482.988775841852, 13489.258090805002, 18996.33512827058, 2088.4778805298674, 2134.9858817061927, 2177.5536015116863, 2213.9335819925427, 2427.757224277576, 9639.373440726331, 9650.324477286593, 9983.351082179708, 9997.15024222942, 10047.849282260771, 1666.4917200253917, 1733.7832770903638, 1737.2453821640108, 1798.183834327339, 1965.1156979359346, 113062.43468782604, 117380.0, 118294.0, 119557.20664190044, 122085.0, 11277.661670149908, 11576.884231157748, 11622.128340374506, 11632.127590430751, 11806.704832379259, 15111.395544178109, 15138.091714497166, 15154.696906062038, 15154.848451515565, 15161.772573411408, 8322.16778322163, 8513.063563008063, 8515.361347898932, 8602.31181505478, 8638.78403039929, 8299.792505187419, 8331.50010999342, 8336.0, 8432.283255923192, 8441.95779021101, 3809.3904975203836, 3882.514685664292, 4491.820327186876, 4559.521250268674, 4645.86062418128, 1814.8094450082815, 1896.8198021187966, 1962.4210857796984, 1964.1848632817507, 1997.131247907136, 854.6752234150982, 858.965641374338, 919.1451949686722, 1012.6607586458472, 1055.714956961616, 19225.711614325795, 20433.48916277075, 20490.385288441073, 20625.17499300041, 20745.481362966046, 926.1479438916214, 931.7484279244702, 962.1052421248306, 970.9514524273899, 994.6817018554138, 10483.266171367932, 10830.350178989285, 10855.945720271347, 10918.945405272923, 10956.28784129029, 616843.0, 618596.0, 623421.0, 629323.0, 642694.0, 130345.0, 134103.0, 135520.32239838934, 136957.0, 139155.0, 921.709661456639, 978.9167920726676, 982.3025651787311, 991.4893829677721, 1034.7878684869434, 15920.761188582412, 16273.186340683154, 16299.0, 16333.509994700178, 16622.91688541598, 50449.556890147614, 51213.48786512087, 51215.48784512256, 52465.21302180394, 52982.20526692025, 76698.0, 77390.0, 77813.44373112393, 77877.61061194658, 79196.93250613815, 6654.0, 7602.50583712057, 7826.060872695313, 8102.87845682327, 8354.958225208835, 11797.410129493663, 12046.397680115784, 12364.62906112818, 12476.251424914533, 12585.87414125847, 10902.345859248471, 11065.506156668747, 11092.001719845253, 11236.269642473213, 11341.829872552084, 10246.23153263523, 10247.333923294962, 10502.842457362993, 10722.678319650424, 10941.343519388862, 9396.624135034523, 10713.142948564253, 11113.888861111285, 11171.497282622464, 11347.262427942158, 7136.000959865519, 7741.264579864904, 7869.567173805267, 8170.714025009096, 8178.591070446573, 9923.950380248052, 10846.837297440703, 11138.220324577525, 11162.939520745516, 11186.328820270484], "value": 9241.676081050175, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1435.669795946933, 1607.6543543138223, 1612.7258366077767, 1888.9905550472245, 2012.0141130845882, 7823.335016523595, 8576.528290944003, 8769.386142969995, 8774.298056155509, 8780.121987801216, 8391.538465384401, 8600.440971336875, 8620.09489003655, 8625.956870215656, 8628.956855215714, 2402.903883844644, 2448.4735781806908, 2449.0448724997264, 2449.865257410842, 2450.2281781238853, 2406.927792166229, 2427.320350301918, 2446.0460420436043, 2447.5472037673, 2448.289995901195, 2379.1435083369975, 2390.247072172269, 2406.7232268289195, 2411.3368823573483, 2413.348395933097, 2389.2712722619594, 2408.8675122868235, 2409.2170044735444, 2420.3586049696846, 2421.806255499556, 2378.750231225714, 2403.9639405408934, 2408.1089996701157, 2411.2043025801445, 2413.3001429585574, 8434.451760635538, 8542.74371768848, 8676.305895528343, 8677.958644962582, 8692.0, 5165.690058596496, 5361.356637203521, 5362.892742145135, 5372.516473517363, 5374.838754837361, 3425.5270233799456, 3453.4301840196335, 3521.5774107107054, 3543.167355671415, 3557.484164796116, 989.8465737810579, 1016.4612755239734, 1017.4759998600726, 1028.8971102889652, 1029.3720830293514, 11288.266262692981, 11408.657740267723, 11417.828732569018, 11461.255018423775, 11468.942655286754, 3725.5343082114737, 4468.374427580141, 4622.422197225347, 4644.467325782483, 4680.485146633868, 9089.909100909039, 9093.499857507768, 9097.727068187965, 9101.453912765255, 9131.634734610674, 4440.444944381952, 5199.012187684328, 5226.425093239816, 5235.764390602432, 5238.161894097002, 4284.186004659167, 4439.866803995886, 4731.550502702238, 4866.780994855169, 4918.532739389752, 3268.297316077055, 3719.363480068761, 3747.831347589365, 3823.9617603824163, 3857.1514266861245, 1195.8684544700077, 1207.221342234254, 1208.812634041725, 1217.792975194219, 1223.7124275795181, 652.8661624366994, 777.3742137579189, 928.7724507495572, 933.9439633621869, 942.7784470649392, 5635.887282254414, 5695.1741997410145, 5711.600187986801, 5768.134779783066, 5897.410258974069, 482.8961773218775, 503.0718324690939, 509.7374851951248, 510.1276816643507, 660.5409240577849, 3742.812859357076, 3895.844166233319, 3955.4660120883887, 4007.719459637856, 4038.9596104038583, 10127.341722787995, 10564.683059508074, 10579.894201058045, 10590.576376944837, 10590.894091059145, 10548.314359566604, 10596.046355827859, 10617.362958222531, 10633.394818269573, 10638.680839574825, 6696.363845434676, 7604.5437273763755, 7993.600319984093, 8091.231333023471, 8133.0, 5765.509931655689, 5859.5019423347785, 5891.9116213255975, 5896.734646940984, 6090.512758979359, 9380.765480863032, 9717.708468745666, 9818.067283608047, 9822.852657210291, 9836.704898853046, 8284.633035549066, 9143.268538517035, 9798.971108033838, 10024.694320306224, 10026.598936042477, 7206.56760594366, 7287.198408174991, 7386.778396648109, 7411.740589079572, 7417.406607471497, 7930.088039875488, 7954.204579542, 8041.437099402986, 8137.79655508617, 8165.856780050916, 7126.251743566851, 7335.4498412618195, 7395.852082958419, 7420.998165247838, 7453.180150183584, 7085.256048114874, 7156.212816590275, 7164.9641751790905, 7295.8176045597165, 7316.085489313836, 6932.168139823253, 6975.895361569682, 6997.405220556309, 7000.929990700028, 7018.192907815666, 4698.624110071254, 4855.490173531728, 4873.610111191168, 4906.63200259989, 5039.974800125976, 4812.738013133961, 4844.006978569314, 4914.8279810207905, 4986.8753281168265, 5139.434662187082], "value": 4440.989138906101, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.65607831385466, 318.7895988647492, 325.4564876655985, 327.4204657755772, 332.01226351604, 5655.293088363955, 5978.923793717129, 5998.790042348519, 6067.24159480065, 6088.421599948009, 5988.461038506533, 6055.273367195931, 6064.787732429366, 6075.544334174943, 6078.696065196745, 3980.4825372701653, 4120.917581648365, 4510.909781804362, 4540.387047748562, 4546.749359067258, 3808.2954653389074, 4386.517483076859, 4565.977170114144, 4571.3828633134435, 4572.702774319659, 4467.508574056851, 4473.552644735533, 4494.910101797962, 4497.595216430521, 4507.639388848901, 4480.372747815309, 4500.954990450103, 4531.796069176895, 4533.569310915458, 4542.500324964251, 3372.2581032172884, 3768.623137686237, 3869.3808990561374, 3878.8836334909997, 3886.455896174538, 5790.073588225906, 5825.970870145665, 5826.2134611827705, 5861.2087368205175, 5872.913511000478, 4445.977770111129, 5262.394824595144, 5262.789488420459, 5274.0, 5294.894102117936, 5539.833804985857, 6090.786822461237, 6375.649339286338, 6447.838804029937, 6449.06488559161, 3638.909027274313, 4282.593153650399, 4309.267424537836, 4311.353297005443, 4330.935035974463, 6396.200474940632, 6454.935450645527, 6775.0, 6791.796246112624, 6804.353586409283, 4060.7563546187325, 4380.540043295472, 4420.524506276432, 4424.889377765581, 4437.889052773707, 4454.487733910577, 4455.353973673799, 4478.708883922534, 4487.730736155776, 4497.617702495259, 4711.646626502958, 5017.247412888096, 5253.4221235664045, 5285.339332583427, 5295.973520132375, 5569.498745112959, 6298.181236439325, 6383.616982981036, 6399.360063993563, 6451.225852897683, 1503.5188739603223, 1538.4461593826213, 1538.638419971306, 1538.6614944712146, 1543.3672194400242, 991.3358050106302, 1015.6035451254525, 1029.912457441126, 1030.7886883188928, 1039.9792004159874, 5883.970580147157, 5928.229330187048, 5958.970205148947, 5972.880542389128, 6024.337322894478, 6555.868882622321, 6576.572522786004, 6594.73621055162, 6601.207855057424, 6622.9006564901565, 5256.369235691742, 5686.05176083064, 5912.793052243151, 5949.530944658005, 5971.9701401493585, 3458.152752575635, 3461.7922924624604, 3466.4867970721025, 3488.755787094879, 3532.31119931616, 6510.479161667055, 6662.459564614624, 6856.314368563104, 6865.382115609619, 6883.449324054164, 5575.526080283222, 6031.095335699592, 6747.0, 6750.35871592198, 6790.287019862942, 6009.459148676553, 6213.954959451707, 6598.670066496752, 6836.965815170893, 6907.585544867324, 6167.6916154193, 6419.807405777834, 6530.91010876534, 6541.2477565078725, 6546.607203567801, 6325.936740632535, 6457.870842583216, 6469.223693156851, 6492.870142597216, 6572.882609956223, 5219.32148820666, 5679.886402271849, 6433.678316084083, 6899.379055884794, 6962.756303529352, 3821.675157611634, 4268.402423660628, 4276.615104640598, 4317.136572685538, 4377.715448495837, 5387.730613469389, 5846.532277417711, 6590.462671871268, 6637.2699003108655, 6657.500687448364, 6176.491161452075, 6446.161998940108, 6449.322821103904, 6468.708908099053, 6477.1579694639395, 6393.520485963664, 6399.304184391047, 6422.518311126591, 6426.742930282736, 6438.774642887476, 5801.82594522165, 5900.64596124234, 6274.780382686767, 6294.5593808434805, 6325.240971083499, 5393.973030134981, 6447.196154268816, 6451.225852897683, 6480.675966201577, 6502.122213501117, 6096.683260361035, 6299.590526615755, 6310.2743184534365, 6332.398422149889, 6340.71466784005], "value": 4669.619121558785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.4927055437865, 689.4868205391349, 697.3549466743261, 722.1154086244351, 782.7338704840355, 14986.175760333177, 15039.097654140733, 15415.768763468543, 15432.382704691812, 15436.228188590581, 13926.0, 14112.15327080376, 14173.936954728386, 14183.929080354585, 14209.076410033367, 740.4594645908463, 915.8168366326728, 923.9491827949477, 924.0805398628374, 925.6158694141923, 739.2828955912782, 897.3270047464401, 938.6949241496507, 939.5912777941618, 941.1153515695258, 971.5093877591806, 973.2068364283122, 974.3715303629155, 975.6195083917278, 976.077606661705, 749.8462815122941, 939.6241503398627, 965.6137544981993, 967.2455484721893, 967.6468089147481, 725.3725527418798, 725.9382952449048, 726.0017475970541, 728.9927100728978, 734.3574372424129, 15077.924610376878, 15175.848241517553, 15207.61980950474, 15215.08709477435, 15222.847771522365, 12702.0, 12800.423980920881, 12821.871781282161, 12827.166234194841, 13082.934585327108, 8721.60752766127, 9593.328467007312, 10029.247806414549, 10128.797424051552, 10283.434411107386, 3478.1826270826336, 3518.2259902821593, 3563.8396272167815, 3564.7326450516057, 3577.6601222883783, 86087.56956215242, 86231.0, 86452.0, 86561.56719216426, 88282.5585872073, 9465.621375144918, 9899.010098990044, 9922.900770992197, 10037.69886903394, 10085.747856303651, 23102.65346019811, 23232.186873459348, 23344.299671009896, 23405.53188936212, 23416.882915585313, 7492.1384040836, 7554.206808285051, 7594.468387212842, 7646.082470103624, 7667.118281397599, 3936.488256526633, 3963.3262345400776, 3976.0855003348815, 3983.6813054955533, 4103.876883693494, 3616.87340943071, 3693.593704692482, 3695.5380577427823, 3700.9814950925074, 3706.2031663192543, 659.7163219815429, 662.3244290823333, 689.582802404538, 704.2007321689816, 705.2277755857328, 518.6758276077452, 538.4238864415038, 568.2045136808501, 571.9256496655408, 601.2304250559291, 7901.407394445439, 7969.521828690297, 8072.717454889051, 8242.752717418487, 8536.359773017048, 519.2237604780802, 520.622548652224, 529.1375058654446, 542.2625229687621, 596.7642781101528, 3881.8059097045034, 3938.606139386096, 4109.527404348479, 4124.835006599762, 4234.13200293939, 87433.56283218543, 88843.11156888348, 89679.0, 90231.0, 90979.0, 75345.0, 77584.61207693926, 80193.0, 81566.0, 81921.3615727694, 67839.66080169666, 70007.64996174986, 71345.64327178331, 71820.0, 72057.27942720505, 6803.693833777393, 8209.850620912957, 8232.382571307056, 8290.760910275925, 8393.496390216607, 44368.0, 45077.64767056994, 45250.86872828074, 45312.54687453083, 45340.0, 64989.70020599656, 65110.67444662746, 65112.34887651063, 65511.0, 65877.0, 10969.122470202525, 11142.108631309313, 11151.609693660683, 11180.161487888277, 11993.040556755614, 10193.74515637115, 11851.0, 12078.21491603043, 12372.381380931098, 12498.75002499963, 11387.487563059847, 11466.31202127875, 11582.957533821997, 11815.940920295343, 12036.63890083299, 11259.0, 11310.377929213972, 11324.773504529698, 11639.76720465569, 11686.766264674829, 11341.773164536828, 11390.031847293072, 11835.349055802013, 11924.284542927453, 12332.81500777507, 9356.48539330316, 9626.9037309626, 9773.9022609773, 9948.751281218027, 10025.749356266151, 9588.328816982745, 9739.269554783561, 9861.09459834337, 10035.69892903214, 10170.389776613427], "value": 6939.674364695625, "unit": "score"}], "name": "dromaeo_css", "value": 7188.738850274443, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 113.0, 112.0, 113.0, 112.0, 121.0, 111.0, 112.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 121.0, 147.0, 201.0, 148.0, 143.0, 139.0, 121.0, 172.0, 121.0], "value": 147.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 185.0, 184.0, 186.0, 183.0, 182.0, 186.0, 185.0, 195.0], "value": 185.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 84.0, 91.0, 57.0, 57.0, 58.0, 80.0, 85.0, 55.0], "value": 71.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 102.0, 135.0, 82.0, 79.0, 78.0, 129.0, 80.0, 78.0, 123.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 87.0, 82.0, 87.0, 85.0, 87.0, 84.0, 88.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 94.0, 93.0, 92.0, 94.0, 94.0, 93.0, 93.0, 114.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 143.0, 85.0, 84.0, 84.0, 86.0, 84.0, 86.0, 86.0, 85.0], "value": 92.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 58.0, 58.0, 59.0, 57.0, 80.0, 58.0, 55.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 65.0, 78.0, 69.0, 71.0, 65.0, 91.0, 97.0, 97.0], "value": 76.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 80.0, 82.0, 81.0, 80.0, 81.0, 80.0, 84.0, 84.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 141.0, 140.0, 140.0, 138.0, 138.0, 157.0, 153.0, 193.0, 144.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 204.0, 220.0, 194.0, 168.0, 167.0, 169.0, 170.0, 173.0, 169.0], "value": 180.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 70.0, 70.0, 70.0, 87.0, 97.0, 93.0, 92.0], "value": 78.3, "unit": "score"}], "name": "kraken", "value": 1513.0, "alertThreshold": 2.0}]} 14:30:26 INFO - Return code: 0 14:30:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:30:26 ERROR - # TBPL SUCCESS # 14:30:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:30:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:30:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:30:26 INFO - 2016-04-21 14:30:26.458 screenresolution[1685:10384] starting screenresolution argv=screenresolution get 14:30:26 INFO - 2016-04-21 14:30:26.461 screenresolution[1685:10384] Display 0: 1600x1200x32@60 14:30:26 INFO - 2016-04-21 14:30:26.467 screenresolution[1686:10387] starting screenresolution argv=screenresolution list 14:30:26 INFO - Available Modes on Display 0 14:30:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:30:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:30:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:30:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:30:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:30:26 INFO - Intel Iris: 14:30:26 INFO - Chipset Model: Intel Iris 14:30:26 INFO - Type: GPU 14:30:26 INFO - Bus: Built-In 14:30:26 INFO - VRAM (Dynamic, Max): 1536 MB 14:30:26 INFO - Vendor: Intel (0x8086) 14:30:26 INFO - Device ID: 0x0a2e 14:30:26 INFO - Revision ID: 0x0009 14:30:26 INFO - Displays: 14:30:26 INFO - Display: 14:30:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:30:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:30:26 INFO - Display Serial Number: 1600x1200 60 14:30:26 INFO - Main Display: Yes 14:30:26 INFO - Mirror: Off 14:30:26 INFO - Online: Yes 14:30:26 INFO - Rotation: Supported 14:30:26 INFO - Return code: 0 14:30:26 INFO - Running post-action listener: _resource_record_post_action 14:30:26 INFO - Running post-run listener: _resource_record_post_run 14:30:27 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 32468480; Write bytes: 695661056; Read time: 359; Write time: 1917 14:30:27 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 375697920; Write bytes: 369866240; Read time: 27950; Write time: 725 14:30:27 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 31542784; Write bytes: 297018880; Read time: 345; Write time: 1132 14:30:27 INFO - Running post-run listener: _upload_blobber_files 14:30:27 INFO - Blob upload gear active. 14:30:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:30:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:30:27 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:30:27 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:30:28 INFO - (blobuploader) - INFO - Open directory for files ... 14:30:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:30:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:30:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:30:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:30:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:30:29 INFO - (blobuploader) - INFO - Done attempting. 14:30:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:30:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:30:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:30:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:30:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:30:31 INFO - (blobuploader) - INFO - Done attempting. 14:30:31 INFO - (blobuploader) - INFO - Iteration through files over. 14:30:31 INFO - Return code: 0 14:30:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:30:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:30:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/896c51e5728d14df382b2af924d110a8cbd391e9bdec31dd6e120dc0babcd8acc917490d30ebf2ed713010170b5e39248aebcbd56c6b8619829c7b6c35efb0dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e15e75ef5c301c3e9b39e064496058c59055e047068a29155518c5ba137f804dfe056217290337ffe9a262d033648491156e0704677f315ac34c8eaeab95f036"} 14:30:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:30:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:30:31 INFO - Contents: 14:30:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/896c51e5728d14df382b2af924d110a8cbd391e9bdec31dd6e120dc0babcd8acc917490d30ebf2ed713010170b5e39248aebcbd56c6b8619829c7b6c35efb0dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e15e75ef5c301c3e9b39e064496058c59055e047068a29155518c5ba137f804dfe056217290337ffe9a262d033648491156e0704677f315ac34c8eaeab95f036"} 14:30:31 INFO - Running post-run listener: copy_logs_to_upload_dir 14:30:31 INFO - Copying logs to upload dir... 14:30:31 INFO - mkdir: /builds/slave/test/build/upload/logs 14:30:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.258140 ========= master_lag: 1.70 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-04-21 14:30:31.114209) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 14:30:31.122999) ========= 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.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/896c51e5728d14df382b2af924d110a8cbd391e9bdec31dd6e120dc0babcd8acc917490d30ebf2ed713010170b5e39248aebcbd56c6b8619829c7b6c35efb0dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e15e75ef5c301c3e9b39e064496058c59055e047068a29155518c5ba137f804dfe056217290337ffe9a262d033648491156e0704677f315ac34c8eaeab95f036"} build_url:https://queue.taskcluster.net/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011040 build_url: 'https://queue.taskcluster.net/v1/task/MewEQwd_TUqgBaweXASXBw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/896c51e5728d14df382b2af924d110a8cbd391e9bdec31dd6e120dc0babcd8acc917490d30ebf2ed713010170b5e39248aebcbd56c6b8619829c7b6c35efb0dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e15e75ef5c301c3e9b39e064496058c59055e047068a29155518c5ba137f804dfe056217290337ffe9a262d033648491156e0704677f315ac34c8eaeab95f036"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 14:30:31.156659) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 14:30:31.156942) ========= 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.Qxy1QXnHX7/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.U1C8mxqFlh/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.005127 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 14:30:31.209984) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 14:30:31.210278) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 14:30:31.212618) ========= ========= Total master_lag: 6.37 =========