builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0293 starttime: 1464935173.52 results: success (0) buildid: 20160602213110 builduid: 08815aab032a4f868d470f8fc385f827 revision: effbb9ad4e93c4383e484f3a515d23194d3c6329 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 23:26:13.520511) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 23:26:13.520936) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 23:26:13.521297) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 23:26:13.596425) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 23:26:13.597272) ========= 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.7lrtEBzJm0/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.jZHuMRxWNW/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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-06-02 23:26:13-- 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% 175M=0s 2016-06-02 23:26:14 (175 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.613315 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 23:26:14.231384) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 23:26:14.231724) ========= 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.7lrtEBzJm0/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.jZHuMRxWNW/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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.025068 ========= master_lag: 0.38 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 23:26:14.638234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-06-02 23:26:14.638605) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev effbb9ad4e93c4383e484f3a515d23194d3c6329 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev effbb9ad4e93c4383e484f3a515d23194d3c6329 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7lrtEBzJm0/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.jZHuMRxWNW/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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-06-02 23:26:14,723 truncating revision to first 12 chars 2016-06-02 23:26:14,724 Setting DEBUG logging. 2016-06-02 23:26:14,724 attempt 1/10 2016-06-02 23:26:14,724 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/effbb9ad4e93?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 23:26:15,882 unpacking tar archive at: mozilla-aurora-effbb9ad4e93/testing/mozharness/ program finished with exit code 0 elapsedTime=1.441018 ========= master_lag: 2.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-06-02 23:26:18.166910) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 23:26:18.170205) ========= script_repo_revision: effbb9ad4e93c4383e484f3a515d23194d3c6329 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 23:26:18.170861) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 23:26:18.171147) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 23:26:18.268454) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2016-06-02 23:26:18.268736) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7lrtEBzJm0/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.jZHuMRxWNW/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 23:26:18 INFO - MultiFileLogger online at 20160602 23:26:18 in /builds/slave/test 23:26:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 23:26:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:26:18 INFO - {'append_to_log': False, 23:26:18 INFO - 'base_work_dir': '/builds/slave/test', 23:26:18 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:26:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:26:18 INFO - 'branch': 'Mozilla-Aurora', 23:26:18 INFO - 'buildbot_json_path': 'buildprops.json', 23:26:18 INFO - 'config_files': ('talos/mac_config.py',), 23:26:18 INFO - 'default_actions': ('clobber', 23:26:18 INFO - 'read-buildbot-config', 23:26:18 INFO - 'download-and-extract', 23:26:18 INFO - 'populate-webroot', 23:26:18 INFO - 'create-virtualenv', 23:26:18 INFO - 'install', 23:26:18 INFO - 'run-tests'), 23:26:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:26:18 INFO - 'download_minidump_stackwalk': True, 23:26:18 INFO - 'download_symbols': 'ondemand', 23:26:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:26:18 INFO - 'tooltool.py': '/tools/tooltool.py', 23:26:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:26:18 INFO - '/tools/misc-python/virtualenv.py')}, 23:26:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:26:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:26:18 INFO - 'installer_path': 'installer.exe', 23:26:18 INFO - 'log_level': 'info', 23:26:18 INFO - 'log_name': 'talos', 23:26:18 INFO - 'log_to_console': True, 23:26:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:26:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:26:18 INFO - 'opt_config_files': (), 23:26:18 INFO - 'pip_index': False, 23:26:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:26:18 INFO - 'cmd': ('bash', 23:26:18 INFO - '-c', 23:26:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:26:18 INFO - 'enabled': True, 23:26:18 INFO - 'halt_on_failure': False, 23:26:18 INFO - 'name': 'check_screen_resolution'},), 23:26:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:26:18 INFO - 'cmd': ('bash', 23:26:18 INFO - '-c', 23:26:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:26:18 INFO - 'enabled': True, 23:26:18 INFO - 'halt_on_failure': False, 23:26:18 INFO - 'name': 'check_screen_resolution'},), 23:26:18 INFO - 'run_cmd_checks_enabled': True, 23:26:18 INFO - 'sps_profile': False, 23:26:18 INFO - 'sps_profile_interval': 0, 23:26:18 INFO - 'suite': 'dromaeojs', 23:26:18 INFO - 'system_bits': '32', 23:26:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:26:18 INFO - 'title': 't-yosemite-r7-0293', 23:26:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:26:18 INFO - 'use_talos_json': True, 23:26:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:26:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:26:18 INFO - 'work_dir': 'build'} 23:26:18 INFO - ##### 23:26:18 INFO - ##### Running clobber step. 23:26:18 INFO - ##### 23:26:18 INFO - Running pre-action listener: _resource_record_pre_action 23:26:18 INFO - Running main action method: clobber 23:26:18 INFO - rmtree: /builds/slave/test/build 23:26:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:26:20 INFO - Running post-action listener: _resource_record_post_action 23:26:20 INFO - ##### 23:26:20 INFO - ##### Running read-buildbot-config step. 23:26:20 INFO - ##### 23:26:20 INFO - Running pre-action listener: _resource_record_pre_action 23:26:20 INFO - Running main action method: read_buildbot_config 23:26:20 INFO - Using buildbot properties: 23:26:20 INFO - { 23:26:20 INFO - "project": "", 23:26:20 INFO - "product": "firefox", 23:26:20 INFO - "script_repo_revision": "production", 23:26:20 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 23:26:20 INFO - "repository": "", 23:26:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 23:26:20 INFO - "buildid": "20160602213110", 23:26:20 INFO - "pgo_build": "False", 23:26:20 INFO - "basedir": "/builds/slave/test", 23:26:20 INFO - "buildnumber": 18, 23:26:20 INFO - "slavename": "t-yosemite-r7-0293", 23:26:20 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 23:26:20 INFO - "platform": "yosemite_r7", 23:26:20 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 23:26:20 INFO - "branch": "mozilla-aurora", 23:26:20 INFO - "repo_path": "releases/mozilla-aurora", 23:26:20 INFO - "revision": "effbb9ad4e93c4383e484f3a515d23194d3c6329", 23:26:20 INFO - "stage_platform": "macosx64", 23:26:20 INFO - "builduid": "08815aab032a4f868d470f8fc385f827", 23:26:20 INFO - "slavebuilddir": "test" 23:26:20 INFO - } 23:26:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 23:26:20 INFO - Running post-action listener: _resource_record_post_action 23:26:20 INFO - ##### 23:26:20 INFO - ##### Running download-and-extract step. 23:26:20 INFO - ##### 23:26:20 INFO - Running pre-action listener: _resource_record_pre_action 23:26:20 INFO - Running main action method: download_and_extract 23:26:20 INFO - mkdir: /builds/slave/test/build/tests 23:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:26:20 INFO - https://queue.taskcluster.net/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:26:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 23:26:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 23:26:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 23:26:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 23:26:50 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 23:26:50 INFO - retry: attempt #1 caught exception: timed out 23:26:50 INFO - retry: Failed, sleeping 30 seconds before retrying 23:27:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #2 23:27:25 INFO - Downloaded 1199 bytes. 23:27:25 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 23:27:25 INFO - Using the following test package requirements: 23:27:25 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 23:27:25 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:27:25 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 23:27:25 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:27:25 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 23:27:25 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:27:25 INFO - u'jsshell-mac.zip'], 23:27:25 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:27:25 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 23:27:25 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 23:27:25 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:27:25 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 23:27:25 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:27:25 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 23:27:25 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:27:25 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 23:27:25 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:27:25 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 23:27:25 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 23:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:27:25 INFO - https://queue.taskcluster.net/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:27:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:27:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:27:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:27:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 23:27:32 INFO - Downloaded 18463793 bytes. 23:27:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:27:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 23:27:35 INFO - Return code: 0 23:27:35 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 23:27: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')]} 23:27:35 INFO - https://queue.taskcluster.net/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:27:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:27:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:27:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:27:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 23:27:38 INFO - Downloaded 18463793 bytes. 23:27:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:27:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 23:27:41 INFO - Return code: 0 23:27:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:27:41 INFO - https://queue.taskcluster.net/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:27:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 23:27:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 23:27:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 23:27:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 23:27:43 INFO - Downloaded 10840145 bytes. 23:27:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:27:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 23:27:44 INFO - Return code: 0 23:27: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')]} 23:27:44 INFO - https://queue.taskcluster.net/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 23:27:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:27:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:27:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 23:27:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 23:27:47 INFO - Downloaded 114167009 bytes. 23:27:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:27:47 INFO - mkdir: /builds/slave/test/properties 23:27:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:27:47 INFO - Writing to file /builds/slave/test/properties/build_url 23:27:47 INFO - Contents: 23:27:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:27:48 INFO - Running post-action listener: _resource_record_post_action 23:27:48 INFO - Running post-action listener: set_extra_try_arguments 23:27:48 INFO - ##### 23:27:48 INFO - ##### Running populate-webroot step. 23:27:48 INFO - ##### 23:27:48 INFO - Running pre-action listener: _resource_record_pre_action 23:27:48 INFO - Running main action method: populate_webroot 23:27:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:27:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:27:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:27:48 INFO - '--tppagecycles', 23:27:48 INFO - '7'], 23:27:48 INFO - 'tests': ['tsvgm']}}, 23:27:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:27:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:27:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:27:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:27:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:27:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:27:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:48 INFO - 'talos_options': ['--disable-e10s'], 23:27:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:27:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:27:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:48 INFO - 'talos_options': ['--disable-e10s'], 23:27:48 INFO - 'tests': ['damp', 'tps']}, 23:27:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:48 INFO - 'tests': ['damp', 'tps']}, 23:27:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:27:48 INFO - 'tests': ['dromaeo_dom']}, 23:27:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:27:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:27:48 INFO - 'tests': ['a11yr', 23:27:48 INFO - 'ts_paint', 23:27:48 INFO - 'tpaint', 23:27:48 INFO - 'sessionrestore', 23:27:48 INFO - 'sessionrestore_no_auto_restore', 23:27:48 INFO - 'tabpaint']}, 23:27:48 INFO - 'other-e10s': {'tests': ['a11yr', 23:27:48 INFO - 'ts_paint', 23:27:48 INFO - 'tpaint', 23:27:48 INFO - 'sessionrestore', 23:27:48 INFO - 'sessionrestore_no_auto_restore', 23:27:48 INFO - 'tabpaint']}, 23:27:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:27:48 INFO - 'ts_paint', 23:27:48 INFO - 'tpaint', 23:27:48 INFO - 'sessionrestore', 23:27:48 INFO - 'sessionrestore_no_auto_restore', 23:27:48 INFO - 'tabpaint']}, 23:27:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:27:48 INFO - 'ts_paint', 23:27:48 INFO - 'tpaint', 23:27:48 INFO - 'sessionrestore', 23:27:48 INFO - 'sessionrestore_no_auto_restore', 23:27:48 INFO - 'tabpaint']}, 23:27:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:27:48 INFO - 'tests': ['a11yr', 23:27:48 INFO - 'ts_paint', 23:27:48 INFO - 'tpaint', 23:27:48 INFO - 'sessionrestore', 23:27:48 INFO - 'sessionrestore_no_auto_restore', 23:27:48 INFO - 'tabpaint']}, 23:27:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:27:48 INFO - 'tests': ['a11yr', 23:27:48 INFO - 'ts_paint', 23:27:48 INFO - 'tpaint', 23:27:48 INFO - 'sessionrestore', 23:27:48 INFO - 'sessionrestore_no_auto_restore', 23:27:48 INFO - 'tabpaint']}, 23:27:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:27:48 INFO - 'tests': ['tsvgx', 23:27:48 INFO - 'tsvgr_opacity', 23:27:48 INFO - 'tart', 23:27:48 INFO - 'tscrollx', 23:27:48 INFO - 'cart']}, 23:27:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:27:48 INFO - 'tsvgr_opacity', 23:27:48 INFO - 'tart', 23:27:48 INFO - 'tscrollx', 23:27:48 INFO - 'cart']}, 23:27:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:48 INFO - 'talos_options': ['--disable-e10s'], 23:27:48 INFO - 'tests': ['tp5o']}, 23:27:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:48 INFO - 'tests': ['tp5o']}, 23:27:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:48 INFO - 'talos_options': ['--disable-e10s', 23:27:48 INFO - '--xperf_path', 23:27:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:27:48 INFO - 'tests': ['tp5n']}, 23:27:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:27:48 INFO - 'talos_options': ['--xperf_path', 23:27:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:27:48 INFO - 'tests': ['tp5n']}}, 23:27:48 INFO - 'talos.zip': {'path': '', 23:27:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:27:48 INFO - Running post-action listener: _resource_record_post_action 23:27:48 INFO - ##### 23:27:48 INFO - ##### Running create-virtualenv step. 23:27:48 INFO - ##### 23:27:48 INFO - Running pre-action listener: _resource_record_pre_action 23:27:48 INFO - Running main action method: create_virtualenv 23:27:48 INFO - Creating virtualenv /builds/slave/test/build/venv 23:27:48 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 23:27:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:27:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:27:48 INFO - Using real prefix '/tools/python27' 23:27:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:27:48 INFO - Installing distribute.............................................................................................................................................................................................done. 23:27:51 INFO - Installing pip.................done. 23:27:51 INFO - Return code: 0 23:27:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f539420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde004c2a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c8e00>, '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 0x10f231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c3b20>, '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.7lrtEBzJm0/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.jZHuMRxWNW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 23:27:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:27:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lrtEBzJm0/Render', 23:27:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:51 INFO - 'HOME': '/Users/cltbld', 23:27:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:51 INFO - 'LOGNAME': 'cltbld', 23:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:51 INFO - 'MOZ_NO_REMOTE': '1', 23:27:51 INFO - 'NO_EM_RESTART': '1', 23:27:51 INFO - 'PAGER': '/bin/cat', 23:27:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:51 INFO - 'PWD': '/builds/slave/test', 23:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:51 INFO - 'SHELL': '/bin/bash', 23:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZHuMRxWNW/Listeners', 23:27:51 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 23:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:51 INFO - 'USER': 'cltbld', 23:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:51 INFO - 'XPC_FLAGS': '0x0', 23:27:51 INFO - 'XPC_SERVICE_NAME': '0', 23:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:52 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 23:27:52 INFO - Cleaning up... 23:27:52 INFO - Return code: 0 23:27:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 23:27: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')]} 23:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27: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')]} 23:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27: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', '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 0x10f529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f539420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde004c2a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c8e00>, '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 0x10f231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c3b20>, '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.7lrtEBzJm0/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.jZHuMRxWNW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 23:27: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', 'psutil>=0.7.1'] in /builds/slave/test/build 23:27: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 psutil>=0.7.1 23:27:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lrtEBzJm0/Render', 23:27:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:52 INFO - 'HOME': '/Users/cltbld', 23:27:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:52 INFO - 'LOGNAME': 'cltbld', 23:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:52 INFO - 'MOZ_NO_REMOTE': '1', 23:27:52 INFO - 'NO_EM_RESTART': '1', 23:27:52 INFO - 'PAGER': '/bin/cat', 23:27:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:52 INFO - 'PWD': '/builds/slave/test', 23:27:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:52 INFO - 'SHELL': '/bin/bash', 23:27:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZHuMRxWNW/Listeners', 23:27:52 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 23:27:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:52 INFO - 'USER': 'cltbld', 23:27:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:52 INFO - 'XPC_FLAGS': '0x0', 23:27:52 INFO - 'XPC_SERVICE_NAME': '0', 23:27:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:52 INFO - Downloading/unpacking psutil>=0.7.1 23:27: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 23:27: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 23:27: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 23:27: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 23:27: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 23:27: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 23:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:27:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:27:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:27:55 INFO - Installing collected packages: psutil 23:27:55 INFO - Running setup.py install for psutil 23:27:55 INFO - building 'psutil._psutil_osx' extension 23:27:55 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 23:27:56 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 23:27:56 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 23:27:56 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 23:27:56 INFO - building 'psutil._psutil_posix' extension 23:27:56 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 23:27:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:27:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:27:56 INFO - ^ 23:27:56 INFO - 1 warning generated. 23:27:56 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 23:27:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:27:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:27:56 INFO - Successfully installed psutil 23:27:56 INFO - Cleaning up... 23:27:56 INFO - Return code: 0 23:27:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 23:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f539420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde004c2a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c8e00>, '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 0x10f231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c3b20>, '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.7lrtEBzJm0/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.jZHuMRxWNW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 23:27:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 23:27:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 23:27:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lrtEBzJm0/Render', 23:27:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:56 INFO - 'HOME': '/Users/cltbld', 23:27:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:56 INFO - 'LOGNAME': 'cltbld', 23:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:56 INFO - 'MOZ_NO_REMOTE': '1', 23:27:56 INFO - 'NO_EM_RESTART': '1', 23:27:56 INFO - 'PAGER': '/bin/cat', 23:27:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:56 INFO - 'PWD': '/builds/slave/test', 23:27:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:56 INFO - 'SHELL': '/bin/bash', 23:27:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZHuMRxWNW/Listeners', 23:27:56 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 23:27:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:56 INFO - 'USER': 'cltbld', 23:27:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:56 INFO - 'XPC_FLAGS': '0x0', 23:27:56 INFO - 'XPC_SERVICE_NAME': '0', 23:27:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 23:27:56 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 23:27:56 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 23:27:56 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 23:27:56 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 23:27:56 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 23:27:56 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 23:27:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 23:27:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:27:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 23:27:59 INFO - Installing collected packages: mozsystemmonitor 23:27:59 INFO - Running setup.py install for mozsystemmonitor 23:27:59 INFO - Successfully installed mozsystemmonitor 23:27:59 INFO - Cleaning up... 23:27:59 INFO - Return code: 0 23:27:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:27:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:59 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 0x10f529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f539420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde004c2a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c8e00>, '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 0x10f231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c3b20>, '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.7lrtEBzJm0/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.jZHuMRxWNW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 23:27:59 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 23:27:59 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 23:27:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lrtEBzJm0/Render', 23:27:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:59 INFO - 'HOME': '/Users/cltbld', 23:27:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:59 INFO - 'LOGNAME': 'cltbld', 23:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:59 INFO - 'MOZ_NO_REMOTE': '1', 23:27:59 INFO - 'NO_EM_RESTART': '1', 23:27:59 INFO - 'PAGER': '/bin/cat', 23:27:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:59 INFO - 'PWD': '/builds/slave/test', 23:27:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:59 INFO - 'SHELL': '/bin/bash', 23:27:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZHuMRxWNW/Listeners', 23:27:59 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 23:27:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:59 INFO - 'USER': 'cltbld', 23:27:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:59 INFO - 'XPC_FLAGS': '0x0', 23:27:59 INFO - 'XPC_SERVICE_NAME': '0', 23:27:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:59 INFO - Downloading/unpacking blobuploader==1.2.4 23:27:59 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 23:27:59 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 23:27:59 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 23:27:59 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 23:27:59 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 23:27:59 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 23:28:02 INFO - Downloading blobuploader-1.2.4.tar.gz 23:28:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:28:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:28:02 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 23:28:02 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 23:28:02 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 23:28:02 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 23:28:02 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 23:28:02 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 23:28:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:28:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:03 INFO - Downloading docopt-0.6.1.tar.gz 23:28:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:28:03 INFO - Installing collected packages: blobuploader, requests, docopt 23:28:03 INFO - Running setup.py install for blobuploader 23:28:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:28:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:28:03 INFO - Running setup.py install for requests 23:28:04 INFO - Running setup.py install for docopt 23:28:04 INFO - Successfully installed blobuploader requests docopt 23:28:04 INFO - Cleaning up... 23:28:04 INFO - Return code: 0 23:28:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:28:04 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')]} 23:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:04 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')]} 23:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:04 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 0x10f529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f539420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde004c2a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c8e00>, '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 0x10f231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c3b20>, '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.7lrtEBzJm0/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.jZHuMRxWNW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 23:28:04 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 23:28:04 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 23:28:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lrtEBzJm0/Render', 23:28:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:04 INFO - 'HOME': '/Users/cltbld', 23:28:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:04 INFO - 'LOGNAME': 'cltbld', 23:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:04 INFO - 'MOZ_NO_REMOTE': '1', 23:28:04 INFO - 'NO_EM_RESTART': '1', 23:28:04 INFO - 'PAGER': '/bin/cat', 23:28:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:04 INFO - 'PWD': '/builds/slave/test', 23:28:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:04 INFO - 'SHELL': '/bin/bash', 23:28:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZHuMRxWNW/Listeners', 23:28:04 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 23:28:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:04 INFO - 'USER': 'cltbld', 23:28:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:04 INFO - 'XPC_FLAGS': '0x0', 23:28:04 INFO - 'XPC_SERVICE_NAME': '0', 23:28:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:28:04 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-8yFppB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:28:04 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-40mCQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:28:05 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-3wTaWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:28:05 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-tbBKUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:28:05 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-xLUknN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:28:05 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-OQYxqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:28:05 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-kAAaa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:28:05 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-eSnA14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:28:05 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-SzHo1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:28:05 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-aXvyVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:28:05 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-et_nFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:28:05 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-cgIeEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:28:06 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-whLS72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:28:06 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-VypuyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:06 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Dci4AM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:28:06 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-33Hcfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:28:06 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-o5ru8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:28:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:28:06 INFO - Running setup.py install for manifestparser 23:28:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:28:06 INFO - Running setup.py install for mozcrash 23:28:06 INFO - Running setup.py install for mozdebug 23:28:06 INFO - Running setup.py install for mozdevice 23:28:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:28:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:28:07 INFO - Running setup.py install for mozfile 23:28:07 INFO - Running setup.py install for mozhttpd 23:28:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:28:07 INFO - Running setup.py install for mozinfo 23:28:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:28:07 INFO - Running setup.py install for mozInstall 23:28:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:28:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:28:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:28:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:28:07 INFO - Running setup.py install for mozleak 23:28:07 INFO - Running setup.py install for mozlog 23:28:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:28:08 INFO - Running setup.py install for moznetwork 23:28:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:28:08 INFO - Running setup.py install for mozprocess 23:28:08 INFO - Running setup.py install for mozprofile 23:28:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:28:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:28:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:28:08 INFO - Running setup.py install for mozrunner 23:28:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:28:08 INFO - Running setup.py install for mozscreenshot 23:28:08 INFO - Running setup.py install for moztest 23:28:09 INFO - Running setup.py install for mozversion 23:28:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:28:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:28:09 INFO - Cleaning up... 23:28:09 INFO - Return code: 0 23:28:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:28:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:09 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 0x10f529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f539420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde004c2a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c8e00>, '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 0x10f231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c3b20>, '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.7lrtEBzJm0/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.jZHuMRxWNW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 23:28:09 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 23:28:09 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 23:28:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lrtEBzJm0/Render', 23:28:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:09 INFO - 'HOME': '/Users/cltbld', 23:28:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:09 INFO - 'LOGNAME': 'cltbld', 23:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:09 INFO - 'MOZ_NO_REMOTE': '1', 23:28:09 INFO - 'NO_EM_RESTART': '1', 23:28:09 INFO - 'PAGER': '/bin/cat', 23:28:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:09 INFO - 'PWD': '/builds/slave/test', 23:28:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:09 INFO - 'SHELL': '/bin/bash', 23:28:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZHuMRxWNW/Listeners', 23:28:09 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 23:28:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:09 INFO - 'USER': 'cltbld', 23:28:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:09 INFO - 'XPC_FLAGS': '0x0', 23:28:09 INFO - 'XPC_SERVICE_NAME': '0', 23:28:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:28:09 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-2SuJiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:28:09 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)) 23:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:28:09 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-pZoo_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:28:09 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)) 23:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:28:09 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-h6w68A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:28:09 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)) 23:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:28:09 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-m0bEQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:28:09 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)) 23:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:28:09 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-otXLnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:28:09 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)) 23:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:28:09 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-lqpFWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:28:10 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)) 23:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:28:10 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-P1GKee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:28:10 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)) 23:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:28:10 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-D15OJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:28:10 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)) 23:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:28:10 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-v8AF0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:28:10 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)) 23:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:28:10 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-I6en_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:28:10 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)) 23:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:28:10 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-0vbzGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:28:10 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)) 23:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:28:10 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Li2KHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:28:10 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)) 23:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:28:10 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-c3mCsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:28:10 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)) 23:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:28:10 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-5z6GSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:28:10 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)) 23:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:10 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-3FK4LF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:11 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)) 23:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:28:11 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ggtxWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:28:11 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)) 23:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:28:11 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-BXArFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:28:11 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)) 23:28:11 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)) 23:28:11 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)) 23:28:11 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)) 23:28:11 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)) 23:28:11 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)) 23:28:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:13 INFO - Downloading blessings-1.6.tar.gz 23:28:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:28:13 INFO - Installing collected packages: blessings 23:28:13 INFO - Running setup.py install for blessings 23:28:13 INFO - Successfully installed blessings 23:28:13 INFO - Cleaning up... 23:28:14 INFO - Return code: 0 23:28:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:28:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:28:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:28:14 INFO - Reading from file tmpfile_stdout 23:28:14 INFO - Current package versions: 23:28:14 INFO - blessings == 1.6 23:28:14 INFO - blobuploader == 1.2.4 23:28:14 INFO - docopt == 0.6.1 23:28:14 INFO - manifestparser == 1.1 23:28:14 INFO - mozInstall == 1.12 23:28:14 INFO - mozcrash == 0.17 23:28:14 INFO - mozdebug == 0.1 23:28:14 INFO - mozdevice == 0.48 23:28:14 INFO - mozfile == 1.2 23:28:14 INFO - mozhttpd == 0.7 23:28:14 INFO - mozinfo == 0.9 23:28:14 INFO - mozleak == 0.1 23:28:14 INFO - mozlog == 3.1 23:28:14 INFO - moznetwork == 0.27 23:28:14 INFO - mozprocess == 0.22 23:28:14 INFO - mozprofile == 0.28 23:28:14 INFO - mozrunner == 6.11 23:28:14 INFO - mozscreenshot == 0.1 23:28:14 INFO - mozsystemmonitor == 0.0 23:28:14 INFO - moztest == 0.7 23:28:14 INFO - mozversion == 1.4 23:28:14 INFO - psutil == 3.1.1 23:28:14 INFO - requests == 1.2.3 23:28:14 INFO - wsgiref == 0.1.2 23:28:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:28:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:14 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 0x10f529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f539420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde004c2a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c8e00>, '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 0x10f231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c3b20>, '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.7lrtEBzJm0/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.jZHuMRxWNW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 23:28:14 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 23:28:14 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 23:28:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lrtEBzJm0/Render', 23:28:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:14 INFO - 'HOME': '/Users/cltbld', 23:28:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:14 INFO - 'LOGNAME': 'cltbld', 23:28:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:14 INFO - 'MOZ_NO_REMOTE': '1', 23:28:14 INFO - 'NO_EM_RESTART': '1', 23:28:14 INFO - 'PAGER': '/bin/cat', 23:28:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:14 INFO - 'PWD': '/builds/slave/test', 23:28:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:14 INFO - 'SHELL': '/bin/bash', 23:28:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZHuMRxWNW/Listeners', 23:28:14 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 23:28:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:14 INFO - 'USER': 'cltbld', 23:28:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:14 INFO - 'XPC_FLAGS': '0x0', 23:28:14 INFO - 'XPC_SERVICE_NAME': '0', 23:28:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:14 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)) 23:28:14 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)) 23:28:14 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)) 23:28:14 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)) 23:28:14 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)) 23:28:14 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)) 23:28:14 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)) 23:28:14 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)) 23:28:14 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)) 23:28:14 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)) 23:28:14 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)) 23:28:14 INFO - Cleaning up... 23:28:14 INFO - Return code: 0 23:28:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:28:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f539420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde004c2a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c8e00>, '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 0x10f231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c3b20>, '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.7lrtEBzJm0/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.jZHuMRxWNW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 23:28:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:28:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:28:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lrtEBzJm0/Render', 23:28:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:14 INFO - 'HOME': '/Users/cltbld', 23:28:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:14 INFO - 'LOGNAME': 'cltbld', 23:28:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:14 INFO - 'MOZ_NO_REMOTE': '1', 23:28:14 INFO - 'NO_EM_RESTART': '1', 23:28:14 INFO - 'PAGER': '/bin/cat', 23:28:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:14 INFO - 'PWD': '/builds/slave/test', 23:28:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:14 INFO - 'SHELL': '/bin/bash', 23:28:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZHuMRxWNW/Listeners', 23:28:14 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 23:28:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:14 INFO - 'USER': 'cltbld', 23:28:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:14 INFO - 'XPC_FLAGS': '0x0', 23:28:14 INFO - 'XPC_SERVICE_NAME': '0', 23:28:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:14 INFO - Downloading/unpacking jsonschema 23:28: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 23:28: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 23:28: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 23:28: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 23:28: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 23:28: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 23:28:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:28:17 INFO - Downloading/unpacking functools32 (from jsonschema) 23:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:17 INFO - Downloading functools32-3.2.3-2.tar.gz 23:28:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:28:17 INFO - warning: no files found matching '*.txt' 23:28:17 INFO - no previously-included directories found matching 'build' 23:28:17 INFO - no previously-included directories found matching 'dist' 23:28:17 INFO - no previously-included directories found matching '.git*' 23:28:17 INFO - Installing collected packages: jsonschema, functools32 23:28:17 INFO - Running setup.py install for functools32 23:28:17 INFO - warning: no files found matching '*.txt' 23:28:17 INFO - no previously-included directories found matching 'build' 23:28:17 INFO - no previously-included directories found matching 'dist' 23:28:17 INFO - no previously-included directories found matching '.git*' 23:28:17 INFO - Successfully installed jsonschema functools32 23:28:17 INFO - Cleaning up... 23:28:18 INFO - Return code: 0 23:28:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:28:18 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')]} 23:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:18 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')]} 23:28:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:18 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 0x10f529a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f539420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde004c2a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c8e00>, '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 0x10f231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c3b20>, '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.7lrtEBzJm0/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.jZHuMRxWNW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 23:28:18 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 23:28:18 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 23:28:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lrtEBzJm0/Render', 23:28:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:18 INFO - 'HOME': '/Users/cltbld', 23:28:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:18 INFO - 'LOGNAME': 'cltbld', 23:28:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:18 INFO - 'MOZ_NO_REMOTE': '1', 23:28:18 INFO - 'NO_EM_RESTART': '1', 23:28:18 INFO - 'PAGER': '/bin/cat', 23:28:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:18 INFO - 'PWD': '/builds/slave/test', 23:28:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:18 INFO - 'SHELL': '/bin/bash', 23:28:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZHuMRxWNW/Listeners', 23:28:18 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 23:28:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:18 INFO - 'USER': 'cltbld', 23:28:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:18 INFO - 'XPC_FLAGS': '0x0', 23:28:18 INFO - 'XPC_SERVICE_NAME': '0', 23:28:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:18 INFO - Downloading/unpacking flake8 23:28:18 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 23:28:18 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 23:28:18 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 23:28:18 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 23:28:18 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 23:28:18 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 23:28:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:28:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:28:20 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 23:28:20 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 23:28:20 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 23:28:20 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 23:28:20 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 23:28:20 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 23:28:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:28:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:28:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:28:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:28:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:28:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:28:21 INFO - no previously-included directories found matching 'docs/_build' 23:28:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:28:22 INFO - Running setup.py install for pep8 23:28:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:28:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:28:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:28:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:28:22 INFO - no previously-included directories found matching 'docs/_build' 23:28:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:28:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:28:22 INFO - Cleaning up... 23:28:22 INFO - Return code: 0 23:28:22 INFO - Running post-action listener: _resource_record_post_action 23:28:22 INFO - Running post-action listener: _start_resource_monitoring 23:28:22 INFO - Starting resource monitoring. 23:28:22 INFO - ##### 23:28:22 INFO - ##### Running install step. 23:28:22 INFO - ##### 23:28:22 INFO - Running pre-action listener: _resource_record_pre_action 23:28:22 INFO - Running main action method: install 23:28:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:28:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:28:22 INFO - Reading from file tmpfile_stdout 23:28:22 INFO - Detecting whether we're running mozinstall >=1.0... 23:28:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:28:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:28:22 INFO - Reading from file tmpfile_stdout 23:28:22 INFO - Output received: 23:28:22 INFO - Usage: mozinstall [options] installer 23:28:22 INFO - Options: 23:28:22 INFO - -h, --help show this help message and exit 23:28:22 INFO - -d DEST, --destination=DEST 23:28:22 INFO - Directory to install application into. [default: 23:28:22 INFO - "/builds/slave/test"] 23:28:22 INFO - --app=APP Application being installed. [default: firefox] 23:28:22 INFO - mkdir: /builds/slave/test/build/application 23:28:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:28:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 23:28:54 INFO - Reading from file tmpfile_stdout 23:28:54 INFO - Output received: 23:28:54 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 23:28:54 INFO - Running post-action listener: _resource_record_post_action 23:28:54 INFO - ##### 23:28:54 INFO - ##### Running run-tests step. 23:28:54 INFO - ##### 23:28:54 INFO - Running pre-action listener: _resource_record_pre_action 23:28:54 INFO - Running main action method: run_tests 23:28:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:28:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:28:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:28:54 INFO - 2016-06-02 23:28:54.866 screenresolution[1645:8351] starting screenresolution argv=screenresolution get 23:28:54 INFO - 2016-06-02 23:28:54.869 screenresolution[1645:8351] Display 0: 1600x1200x32@60 23:28:54 INFO - 2016-06-02 23:28:54.874 screenresolution[1646:8354] starting screenresolution argv=screenresolution list 23:28:54 INFO - Available Modes on Display 0 23:28:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:28:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:28:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:28:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:28:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:28:55 INFO - Intel Iris: 23:28:55 INFO - Chipset Model: Intel Iris 23:28:55 INFO - Type: GPU 23:28:55 INFO - Bus: Built-In 23:28:55 INFO - VRAM (Dynamic, Max): 1536 MB 23:28:55 INFO - Vendor: Intel (0x8086) 23:28:55 INFO - Device ID: 0x0a2e 23:28:55 INFO - Revision ID: 0x0009 23:28:55 INFO - Displays: 23:28:55 INFO - Display: 23:28:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:28:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:28:55 INFO - Display Serial Number: 1600x1200 60 23:28:55 INFO - Main Display: Yes 23:28:55 INFO - Mirror: Off 23:28:55 INFO - Online: Yes 23:28:55 INFO - Rotation: Supported 23:28:55 INFO - Return code: 0 23:28:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:28:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:28:55 INFO - Python 2.7.3 23:28:55 INFO - Return code: 0 23:28:55 INFO - grabbing minidump binary from tooltool 23:28:55 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')]} 23:28:55 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 0x10f3c8e00>, '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 0x10f231db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3c3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:28:55 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 23:28:55 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 23:28:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:28:55 INFO - Return code: 0 23:28:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:28:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:28:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:28:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:28:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:28:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:28:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:28:56 INFO - Return code: 0 23:28:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0293', '--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 23:28:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0293 --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 23:28:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lrtEBzJm0/Render', 23:28:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:28:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:28:56 INFO - 'HOME': '/Users/cltbld', 23:28:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:28:56 INFO - 'LOGNAME': 'cltbld', 23:28:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:28:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:28:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:56 INFO - 'MOZ_NO_REMOTE': '1', 23:28:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:28:56 INFO - 'NO_EM_RESTART': '1', 23:28:56 INFO - 'PAGER': '/bin/cat', 23:28:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:28:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:56 INFO - 'PWD': '/builds/slave/test', 23:28:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:28:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:28:56 INFO - 'SHELL': '/bin/bash', 23:28:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jZHuMRxWNW/Listeners', 23:28:56 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 23:28:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:28:56 INFO - 'USER': 'cltbld', 23:28:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:28:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:28:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:56 INFO - 'XPC_FLAGS': '0x0', 23:28:56 INFO - 'XPC_SERVICE_NAME': '0', 23:28:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:28:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0293', '--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 23:28:56 INFO - mozversion application_buildid: 20160602213110 23:28:56 INFO - mozversion application_changeset: effbb9ad4e93c4383e484f3a515d23194d3c6329 23:28:56 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:28:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:28:56 INFO - mozversion application_name: Firefox 23:28:56 INFO - mozversion application_remotingname: firefox-dev 23:28:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:28:56 INFO - mozversion application_vendor: Mozilla 23:28:56 INFO - mozversion application_version: 48.0a2 23:28:56 INFO - mozversion platform_buildid: 20160602213110 23:28:56 INFO - mozversion platform_changeset: effbb9ad4e93c4383e484f3a515d23194d3c6329 23:28:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:28:56 INFO - mozversion platform_version: 48.0a2 23:28:56 INFO - using testdate: 1464935336 23:28:56 INFO - actual date: 1464935336 23:28:56 INFO - starting webserver on 'localhost:49274' 23:28:56 INFO - SUITE-START | Running 2 tests 23:28:56 INFO - TEST-START | dromaeo_css 23:28:56 INFO - operating with platform_type : mac_ 23:28:56 INFO - Initialising browser for dromaeo_css test... 23:28:56 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpr0h9rC/profile http://localhost:49274/getInfo.html) 23:28:58 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 23:28:58 INFO - PROCESS | 1664 | colorDepth:24 23:28:58 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 23:28:58 INFO - PROCESS | 1664 | __metrics 23:28:58 INFO - PROCESS | 1664 | 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" 23:28:58 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:28:59 INFO - TEST-INFO | 1664: exit 0 23:28:59 INFO - Browser initialized. 23:28:59 INFO - Running cycle 1/1 for dromaeo_css test... 23:28:59 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpr0h9rC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:29:11 INFO - PROCESS | 1667 | RSS: Main: 211312640 23:29:11 INFO - PROCESS | 1667 | 23:30:02 INFO - PROCESS | 1667 | 1464935402934 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] 23:32:13 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 23:32:14 INFO - PROCESS | 1667 | RSS: Main: 325443584 23:32:14 INFO - PROCESS | 1667 | 23:35:17 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 23:35:18 INFO - PROCESS | 1667 | RSS: Main: 357236736 23:35:18 INFO - PROCESS | 1667 | 23:38:20 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 23:38:21 INFO - PROCESS | 1667 | RSS: Main: 360009728 23:38:21 INFO - PROCESS | 1667 | 23:41:23 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 23:41:24 INFO - PROCESS | 1667 | RSS: Main: 356990976 23:41:24 INFO - PROCESS | 1667 | 23:44:27 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 23:44:27 INFO - PROCESS | 1667 | RSS: Main: 326119424 23:44:27 INFO - PROCESS | 1667 | 23:47:30 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 23:47:30 INFO - PROCESS | 1667 | RSS: Main: 389029888 23:47:30 INFO - PROCESS | 1667 | 23:47:30 INFO - PROCESS | 1667 | __start_tp_report 23:47:30 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 23:47:30 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 23:47:30 INFO - PROCESS | 1667 | |i|pagename|runs| 23:47:30 INFO - PROCESS | 1667 | |0;dojo.html;3346.4645656694934;3431.9142021449443;3438.1748380388713;3445.758796884219;3495.9300813983723;5591.30108736408;5670.404607516213;5679.602427830054;5687.232223649801;5688.118341657045;5675.801346952858;5698.145278208271;5720.856978575538;5721.941440833439;5729.054705973509;5121.078205922932;5173.146430838917;5176.689398636075;5285.445028272034;5294.391145018314;5231.790728370861;5262.578993680516;5274.657147285414;5275.683458992454;5280.471952804708;5200.947990520103;5241.580673546107;5251.658642188265;5259.158534634459;5260;5208.427073021983;5241.947580524184;5247.711375874325;5268.631195816294;5298.735063246822;5098.133317336062;5138.7173705446185;5150.381954165487;5201.271821944931;5201.817936372209;5495.340559132889;5528.308961379827;5541.974734674058;5556.111022236463;5563.749631266603;6226.501879849647;6247.937520624827;6255.499560035232;6266.749330026793;6268.49852011838;6093.542984276197;6180.783672571483;6310.495160387204;6316.936830631681;6320.399562041599;5803.245578074823;5818.389069147721;5824.301083869963;5825.737841797045;5858.912116318259;6329.398707122816;6343.302236753953;6343.904841427382;6347.968260158762;6352.2694890087305;5989.580729348991;6095.238095238095;6101.511879049665;6133.969330153321;6185.752569897244;6134.467201660938;6175.135481032659;6200.937990620127;6204.534659900435;6206.131141640174;6114.753609057711;6231.688415579203;6242.500599951993;6246.562740608114;6252.374762523802;5183.300254465675;6213.937860621336;6244.094606282064;6262.906056409158;6286.308506064329;4815.133276010281;4876.756162191876;4917.803287868445;4925.704457732548;4925.729084900365;4372.213001659668;4402.911941761146;4431;4444.2222611042935;4453.554644535585;5672.262605861211;5713.914291285634;5715.828525144252;5725;5740.196372507852;6120.812855101226;6240.968795155995;6246.593971391845;6338.144350512622;6356.14192084072;5649.209110724501;5655.688937108499;5668.433156684298;5685.829425117253;5687.800926967537;5310.097283461743;5342.492463215988;5356.7857285708915;5384.246205531229;5387.111126664056;6296.43332100104;6312.873742525216;6322.936770632235;6326.4306212442025;6339.461145802658;6138.081874381997;6214;6262.46769024638;6310.5898116622275;6337.397947195009;6258.3428739983465;6297.811065668037;6313.810585682437;6317.30509643948;6331.366863313632;6088.360722124113;6151.907721384094;6161.476274516537;6164.137020816999;6167.537434692326;6121.635889161253;6223.159873417031;6282.528810339334;6297.086922396318;6308.842278943063;5842.883142337215;6134.570580059353;6142.437386316015;6148.077788331786;6164.7842325518395;5691.886162276648;6124.5712800103565;6128.8774224516155;6138.1713468681155;6142.846428839136;4846.321514987975;5883.0881213411985;5886.20536227621;5949.107633854784;5981.1327357532045;6082.939170608237;6107.175531303395;6116.5718399711595;6120.791457599439;6147.385261473817;5855.707214639337;5942.346341902301;5959.255093113361;5967.641941483351;5988.970055149871 23:47:30 INFO - PROCESS | 1667 | |1;ext.html;485.87124412030806;529.2299703930781;538.8733647592813;540.2895192821439;575.5827025406578;30312.39375212497;31583.684163158363;31600.20999475014;31642.367152656934;38274.234515309676;43899;43945;44136.99999999992;44160.675179744656;44218.557814421845;9481.71554853352;9753.21974242059;9758;9761.219102471821;9765.609375625001;8598.355123365773;8775.517346545937;8795.604197811082;9488.288378371615;9634.181094606967;11477.024452921512;11655.533778648844;11659.358735269558;11668.1832271741;11669.358185299847;11265.324080555192;11369.772604547905;11375.943120284428;11384.829227561593;11388.943055284753;8619.870701939475;11328.943355283252;11359.204855660024;11452.599159029394;11555.19113662044;31986;38442.80778596089;38993.22013559741;39013;39031;3069.2633767895545;3105.316830297331;3318.950215746766;3401.5918089829133;3573.4818451324672;27081.1875643731;27469.725302746916;27703.861480692467;28272.43455130886;28619;2729.8089133760445;2757.5174344489633;2764.5853122031654;2767.764739997103;2811.2550174203852;319551;321584;322263.38868305745;323329;323606;35967.28065438729;36645.63354366422;36650.63349366526;36694.449583256275;36711.449328260096;35184.94445166649;35398;35406;35450.64549354525;35484.29031419409;14485.78271325931;15516.069035857885;15758.369665213264;15772.132532710582;15773.526794196192;10516.737081573021;13322.333883305795;13328.800067998987;13787.793183102262;13798.724025519634;7791.610419479003;7896.881546776803;8175.918240817516;8215.219554142346;8331.8333633327;2923;3344.816035118043;3411.164264755101;3418.418868792311;3448.1724386147143;926.3422969691594;930.316217580078;1044.081208536483;1085.0776839686362;1113.1373185781085;25776;28663;29060.564091538643;29353.11940641784;29396.706032939826;808.6482380164647;1049.9395610433364;1113.9721506962228;1199.208522375228;1296.3259105265208;12543.811842822364;13507.864921350858;14757.040792348464;14920.253987300592;15197.316120774592;624007;633026;633106;634201;634752;58674.41325586689;60780.392196078355;60867.695661522295;60871.391286087455;60896.173114806625;1365.2286458151113;1422.2888555722138;1471.4040813470563;1495.1776522912355;1507.4347119830063;28363.290917727223;28367.858160709064;28804.135875923756;29419.705802941695;29471.85264073666;46940;47239.055218896116;47460;47740.7612961933;47857.28214076861;362438;364960;365152;365215;623323;26522.336941576614;27306.180814575593;27587.586186206823;27607.723922760517;27658.5851212236;41431.37852932148;44117;44215.778921105186;44259.55740442554;44586.10827783491;30701.23246918845;36778.63221367752;36963.63036369602;37689;37701.434478483396;36293.455598166576;36379.45430818593;36532.81733591315;36718.082047949014;37037.62962370342;35332.47001295034;36038.27923441569;36264.45603316005;37218.255634886606;37300;31237.219069523442;31862.362752744353;32026.35947280995;32237;32288.83855580707;19634.705479418106;24378.51242975166;24675;24772.25683229506;24854.88153033082 23:47:30 INFO - PROCESS | 1667 | |2;jquery.html;386.97097717671176;400.5753900865083;401.2436557089886;405.98376064957404;413.72280572016746;13519;17262.913685431588;20013.699794503074;20672.586548269024;20688.793112068874;15057.096574205556;16340;20593.38219853403;20771.68842467364;20851.791482085173;5631.549476041927;5772.884542309152;5935.495482883938;5955;5959.761609535613;4184.288670925935;5847.736851841656;5954.374790646984;5956.52347812174;6296.716647750839;5835.562332825034;6379.170707808002;6552.770653027145;6560.179977502812;6568.277489476154;6293.213348331458;6450.774222902222;6533.803985880431;6540.542162048659;6543.672816359163;5605.131204663284;5651.434856514356;5652.349979752299;5660.518855897212;5665.575081868877;23669.28992130239;25627.74372256272;25880.352991175183;25982.22053338403;26036.609450858254;2214.280358883353;2324.8721320327377;2347.084636991575;2511.2717311979504;2890.682024977251;14152;14730.410783568645;14911.850881491155;14926.328315225841;15067.773983390258;1544.9536513904488;1712.7516510105966;1727.360876475712;1753.4739578126512;1780.97328540072;96632;99242.5037874806;100225;103169.48415257876;104689;9330.393524420891;10739.731506712238;10996.725081873017;11171.727466203032;11177.32936023825;14053.016288859682;14154.646133846736;14176.645583860485;14187.503437379642;14199.21904295253;7837.629819177694;8165.183481651906;8167.754967350989;8185.836283274421;8759.562021899006;7359;8114.513129212266;8139.552324622085;8145.714899978472;8870.379073464925;5608.298962629671;6483.740650374027;6572.572782769104;6583.80248592543;6589.86820263592;1587.5078725594872;1708.7611481675685;1785.9821401786076;1870.6819840627127;1871.0644677661169;971.9465429401309;979.8089372572423;1012.1700205831148;1032.256775121911;1081.3619964221148;17135.742963855304;20080.799192007893;20203.39389818308;20316.796832031785;20758.792412075687;1018.2159737002466;1018.4653057145058;1039.2465462539599;1054.225144518778;1065.5684447798653;11280.435978201058;11325.886741132483;11334.546618135348;11363;11365.886341136647;600416;605496;618358.2656693772;637032;638915;60910.695446522484;108914.45542772394;110227.44886275518;113553;123299;1014.832552628808;1024.9692509224735;1048.9580416783242;1054.446415631784;1055.551390658967;17274.395396161075;17614.29542818273;17630.382936597158;17658.470245892644;17723.46829595114;35355;48583.27125093197;51063.234051488515;52003.21995170151;52579;71900;72389.27610723826;73045.26954730597;73688.26311736814;73997.6300118496;5477.385188824819;6863.485238607024;7595.012648355679;8144.75565733029;8316.41785075039;9248;11937.28376297425;12345.629631111082;12464.626061218178;12625.116241862981;10735.89264107349;10754.13966882632;10841.321298520794;10903.369581941655;11456.312621242752;10740.30188037775;10931.672049838518;11033.227674062739;11175.497102630563;11376.715582110513;11289;11354;11543.711407214887;11764.294142351486;11800.291982481078;8152.8369432609825;8280.917190828015;8312.127226641116;8315.875261870955;8370.079291278076;10501.78996420052;11077.723056923642;11347;11387;11412.82880756806 23:47:30 INFO - PROCESS | 1667 | |3;mootools.html;1454.9960527236199;1584.7305957987141;1615.3215649427239;1838.3841413126602;1941.4175747275813;7976.720814771485;8628.56857157142;8782.38523303369;8813.074627164151;8817.426867253633;7485.7754267372065;8742.912570874289;8816.4710117393;8856.77858053549;8864.202221800037;2450.5956517174645;2457.9508409831883;2478.0954951442773;2482.515909397668;2483.50329934013;2407.711074671033;2489.8132640051977;2491.364702000987;2493.4389762303517;2504.9373765655914;2235.8099561537288;2349.5535848188874;2443.0716327795412;2480.231128350208;2492.8379655322437;2400.50789588134;2425.1269542964433;2459.004103338151;2472.5796614575474;2486;2435.1963851929;2472.0235506974823;2484.093305943318;2484.5155194737035;2484.689413823272;8507.829843403159;8964.103589641049;9019.774505637346;9027.187553120251;9028.458292502406;5434.456554344534;5569.554435645138;5580;5618.381977982419;5623.915641265385;3416.5558477398026;3475.7393195510185;3506.1760486285702;3512.8946131616094;3530.9293814123575;1020.7958408318366;1022.7954409118132;1027.2193133218732;1033.3283365812283;1033.6175615022414;12075.215111017844;12179.695507612381;12196.878031219663;12206.206596571194;12245.387730613435;4580.679352445363;4738.668293219511;4754.572088512051;4770.356001939763;4776.09254241693;9458.905410945803;9535;9544.284178686627;9556.61773529051;9574.138327550554;5242.501962313574;5377.973110134424;5422.75597598109;5466.207399926989;5483.8080667177255;4307.310830267143;4448.243798554254;4454.10917816438;4455.420795296591;4997.200447928314;3639.727020473475;3749.962500374961;3916;3962.3462128748433;3966.186931678999;1191.6544202181271;1217.1419149499582;1223.1499108119772;1225.350564200975;1242.5092088624942;883.8497455432463;923.9260859131253;935.8021732182817;939.196986576481;945.2154711589365;5856.677882716406;5965.3139888912465;5981.833542459266;6003.369646187175;6045.33501314855;933.3280038372361;997.2072202598952;1043.2019505078622;1051.363924825485;1081.3563383656797;3823.235352929425;3844.2695887781233;3945.0926286953586;3984.6812255019527;4040.8383664653675;10506.737331566772;10835;10865.83701244482;10986.890131098746;11018.834717479243;10699.304545204537;10739.463026848627;10796.51415686296;10843.674689759162;10851.240413171161;8272.875906861402;8353.20644538768;8395.70615028471;8424.620892059873;8526.23263906239;5630.85922851932;5736.541076713769;5877.500412464989;5998.400159983967;6018.789342372996;9121.498317592595;9495.38280011797;9511.048895110434;9519.000504947158;9700.563474643517;9550.997145299922;9672.516374181121;9808.313418060667;9984.151347135285;10011.849822252527;7712.38300935935;7738.303552680286;7843.960780196063;7845.176256493214;7849.332806711265;7278.636068196463;7450.403967682464;8070;8255.390198911155;8457.154284571494;7363.705451782083;7431.442641801778;7485.737999170003;7495.737649182471;7531.322181003517;6283.340249273943;6952.270011648704;7267.709291628275;7408.555486670817;7413.332800048021;6933.445324373937;6994.300569942965;7002.509824312249;7047.40097091753;7049.330313620198;4901.5588597026435;4909.239067944474;4942.728149951788;5016.673916195436;5051.595872330278;4975.477574854733;4983.377077865267;4992.2511623256805;5049.671771334852;5097.464766199496 23:47:30 INFO - PROCESS | 1667 | |4;prototype.html;314.7639270547088;317.303518776286;326.4352669881106;330.48444426694374;331.50771104909205;6489;6491.091247225392;6523.34766523348;6546.541742078057;6568.1789776277965;5229.712365819878;6271.655058971754;6354.0937718684445;6468.159139311882;6480.222373315209;4199.307114326132;4730.172219861528;4853.102176097415;4901.8039278429;4911.459739428669;4912.38595175603;4921.7785199666105;4923.212286034235;4925.926111108336;4935.580475659555;4641.327007583898;4735.3133795599615;4822.5177482251665;4863.2461968394955;4894.04566109607;4773.284007398918;4796.952030479721;4820;4841.298011788306;4863.10032643962;3670.4861319415304;4043.858464953742;4143.751374917484;4150.8547200847825;4163.6669066474915;6290.119383286344;6297.0554416837385;6297.307296197415;6307.810765676991;6313.71588278524;5753;5769.278840144982;5771.971140144315;5772.2496075510335;5783.248177736868;6753.85184518633;6962.756303529402;6988.440924726061;6998.265182155851;7008.334208250208;4657.69724967876;4701.717896926195;4702.788374523155;4706.599939005188;4712.340272361837;6416.871662566769;6836.008778727107;7340.963295183543;7370.078740157481;7385.409167266552;4752.144613969519;4758.262469317261;4767.141914455362;4773.785179666923;4785.258284965836;4838.516148385133;4839.975800120977;4846.079244943449;4852.708837469763;4861.565838077776;4674.953250467452;5361.544268737123;5569.10894256917;5734.827955161351;5739.741711622987;6955.339242771928;7000.879859222602;7005.824854378681;7020.052292940388;7038.331358520933;1534.5242974677885;1587.5475489485673;1614.2171047042175;1616.8706503479693;1618.8381161883717;971.5676523946917;979.0747743382577;982.6609819612222;986.0090608938115;1014.0569270578286;6343.52423568225;6406.2953075161695;6424.453921416639;6436.292007879129;6445.355464453517;7157.964210178916;7159.391451726661;7160.283971602798;7183.784486465414;7196.64016799158;5189.455107213764;6423.068655044993;6423.646699431576;6430.614163150132;6439.259485159173;3676.8400052679503;3762.191128907298;3788.337041017866;3807.8476860925807;3809.1619843634357;7406.370458510979;7462.514936529108;7473.626318684044;7495.962520187473;7497.700091996368;7338.522996005243;7356.117265928123;7358.11702595692;7398.889016664754;7401.333879950723;7439.55362678241;7440.181580026193;7440.7767766966;7447.776566703008;7454.627268636546;5682.857928551819;7139.750108746169;7140.428765698628;7161.283871612797;7364.968904353502;7150.64246787669;7222.06113205301;7229.168645605612;7238.0952380952385;7243.5653860768525;7654.425918056279;7660.425468089804;7670.463067585439;7676.193999630182;7676.80807979805;4594.609458196091;4647.349371088118;4651.604613607881;4654.37165982588;4655.2784318430695;6179.938200617937;6985.476089293222;7215.891761623475;7267.491275610657;7282.271772822675;6952.165740111219;7029.929700703132;7046.753363632249;7047.647617618996;7047.7180912762915;7021.262767409553;7025.508214424941;7030.3321184487395;7035.577865328097;7040.331168538981;6845.110135682329;6874.793756187322;6882.242953275236;6890.138732658418;6891.345322194384;6797.660116994031;7020;7049.541779784297;7054.08296921397;7071.858562828817;6695.464362850863;6709.664516774044;6823.454123669995;6824.146981627297;6884.173899131936 23:47:30 INFO - PROCESS | 1667 | |5;yui.html;639.280809089774;677.2956125629346;737.2701025984277;741.766343601765;745.4707157917875;14403.423863045466;14728.042677225987;14765.409383624656;14951.102933823964;14996.625084372896;11439.885601143986;13626.25055621943;13656.726865462684;13727;13789.89680825534;714.4891402647094;729.806601250669;900.0774206438421;918.6325469812095;921.4195057114009;917.1149840404008;920.645551462685;921.1295325917011;922.6816748221853;926.8378033844084;747.5664114813395;968.4576637083256;969.1519920069939;969.6073090398364;972.7033254857266;946.507815935713;957.2437774158407;959.2086528613921;963.1957315641378;965.1024547171128;713.0373995106617;715.7316006497564;715.8103102677794;716.5843810589845;718.3929579505362;14797.260136993107;14831.851681483264;14839.25803709821;14839.629009274746;14886.255687215596;12241.693957651041;12363.913736911434;12464.501419943192;12566.623001309885;12979.610611681665;9964.950175249078;10043.19654427644;10081.798364032751;10105.696829095139;10693.358398496115;3390.2880395117054;3467.4625433057918;3470.715835140989;3470.843812028465;3492.6158122606494;74152.25847741561;74723.6263818683;74847;75003.12492187685;75266.62366688186;8210.630521626541;9086.500242486594;9368.34421590482;9439.764005899908;9490;21065.578688426147;21080.945952702612;21082.578348432944;21099;21178.576428471344;7251.311125443074;7401.888971665429;7468.178500364956;7491.475596708287;7512.805353656343;4002.1595464952684;4013.57857424972;4076.979615101965;4103.7742924138865;4139.523954745183;3394.694477496988;3459.5329630500064;3464.1512829356466;3466.8613255470004;3469.7224222062678;586.6802592587051;605.7516418268577;613.8526753579198;614.781752477864;626.9749210031548;498.94012718474016;503.9092963266574;504.3519077984772;583.5127668396937;608.7321578505444;8086.070101938352;8123.79690507742;8203;8281.321053604694;8293.087760346358;597.5339235396398;610.3713175429315;612.0788213738384;616.7964571691331;618.6875627807929;3768.2840260350445;3804.7907365095184;3813.4851795007876;3881.8835434936996;3881.9805900970314;85789;87337.56331218303;87782;87806;88089;66929;69976;70327;70458.9431158533;71753;64952.02571961424;65247.36881577904;65993;66133.66933165303;66199.66900165565;7565.886511702219;7619.504732192389;7647.732329368668;7651.273129052731;7666.003419555422;45828.77085614684;46180.845478863295;46184.076318474115;46463.6060918173;46788.7660561695;58798;61397.693011534655;61600.383996159464;62321;62525.68737156285;9922.751931201778;9957.253206009724;10029.348092373972;10115.595376185165;10236;8549.586510365687;10770.51532681047;10922.126229901747;10935.015848573667;11512.654620361403;11217;11267.774644507228;11627.825582616437;11639.59261425846;11848.170628056298;11046.889531104585;11128.268941495298;11308.378039207922;11521.251118677259;12015.038796896402;10733.677989660322;10833.837492437777;10950.178736594946;10978.725531861768;11070.446477676245;9375;9480.95259523698;9486.430814151174;9764.267679924176;9931.65239216624;9253.629854805733;9577.329586928849;9588;9610.567524461556;9764.755881102745 23:47:30 INFO - PROCESS | 1667 | __end_tp_report 23:47:30 INFO - PROCESS | 1667 | __start_cc_report 23:47:30 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1115 23:47:30 INFO - PROCESS | 1667 | __end_cc_report 23:47:30 INFO - PROCESS | 1667 | __startTimestamp1464936450809__endTimestamp 23:47:30 INFO - PROCESS | 1667 | 23:47:30 INFO - PROCESS | 1667 | ------- Summary: start ------- 23:47:30 INFO - PROCESS | 1667 | Number of tests: 6 23:47:30 INFO - PROCESS | 1667 | 23:47:30 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5732.08 Median:5942.35 stddev:637.42 (10.7%) stddev-sans-first:611.28 23:47:30 INFO - PROCESS | 1667 | Values: 3346.5 3431.9 3438.2 3445.8 3495.9 5591.3 5670.4 5679.6 5687.2 5688.1 5675.8 5698.1 5720.9 5721.9 5729.1 5121.1 5173.1 5176.7 5285.4 5294.4 5231.8 5262.6 5274.7 5275.7 5280.5 5200.9 5241.6 5251.7 5259.2 5260.0 5208.4 5241.9 5247.7 5268.6 5298.7 5098.1 5138.7 5150.4 5201.3 5201.8 5495.3 5528.3 5542.0 5556.1 5563.7 6226.5 6247.9 6255.5 6266.7 6268.5 6093.5 6180.8 6310.5 6316.9 6320.4 5803.2 5818.4 5824.3 5825.7 5858.9 6329.4 6343.3 6343.9 6348.0 6352.3 5989.6 6095.2 6101.5 6134.0 6185.8 6134.5 6175.1 6200.9 6204.5 6206.1 6114.8 6231.7 6242.5 6246.6 6252.4 5183.3 6213.9 6244.1 6262.9 6286.3 4815.1 4876.8 4917.8 4925.7 4925.7 4372.2 4402.9 4431.0 4444.2 4453.6 5672.3 5713.9 5715.8 5725.0 5740.2 6120.8 6241.0 6246.6 6338.1 6356.1 5649.2 5655.7 5668.4 5685.8 5687.8 5310.1 5342.5 5356.8 5384.2 5387.1 6296.4 6312.9 6322.9 6326.4 6339.5 6138.1 6214.0 6262.5 6310.6 6337.4 6258.3 6297.8 6313.8 6317.3 6331.4 6088.4 6151.9 6161.5 6164.1 6167.5 6121.6 6223.2 6282.5 6297.1 6308.8 5842.9 6134.6 6142.4 6148.1 6164.8 5691.9 6124.6 6128.9 6138.2 6142.8 4846.3 5883.1 5886.2 5949.1 5981.1 6082.9 6107.2 6116.6 6120.8 6147.4 5855.7 5942.3 5959.3 5967.6 5989.0 23:47:30 INFO - PROCESS | 1667 | 23:47:30 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:58380.48 Median:3331.88 stddev:128270.20 (3849.8%) stddev-sans-first:128556.38 23:47:30 INFO - PROCESS | 1667 | Values: 485.9 529.2 538.9 540.3 575.6 30312.4 31583.7 31600.2 31642.4 38274.2 43899.0 43945.0 44137.0 44160.7 44218.6 9481.7 9753.2 9758.0 9761.2 9765.6 8598.4 8775.5 8795.6 9488.3 9634.2 11477.0 11655.5 11659.4 11668.2 11669.4 11265.3 11369.8 11375.9 11384.8 11388.9 8619.9 11328.9 11359.2 11452.6 11555.2 31986.0 38442.8 38993.2 39013.0 39031.0 3069.3 3105.3 3319.0 3401.6 3573.5 27081.2 27469.7 27703.9 28272.4 28619.0 2729.8 2757.5 2764.6 2767.8 2811.3 319551.0 321584.0 322263.4 323329.0 323606.0 35967.3 36645.6 36650.6 36694.4 36711.4 35184.9 35398.0 35406.0 35450.6 35484.3 14485.8 15516.1 15758.4 15772.1 15773.5 10516.7 13322.3 13328.8 13787.8 13798.7 7791.6 7896.9 8175.9 8215.2 8331.8 2923.0 3344.8 3411.2 3418.4 3448.2 926.3 930.3 1044.1 1085.1 1113.1 25776.0 28663.0 29060.6 29353.1 29396.7 808.6 1049.9 1114.0 1199.2 1296.3 12543.8 13507.9 14757.0 14920.3 15197.3 624007.0 633026.0 633106.0 634201.0 634752.0 58674.4 60780.4 60867.7 60871.4 60896.2 1365.2 1422.3 1471.4 1495.2 1507.4 28363.3 28367.9 28804.1 29419.7 29471.9 46940.0 47239.1 47460.0 47740.8 47857.3 362438.0 364960.0 365152.0 365215.0 623323.0 26522.3 27306.2 27587.6 27607.7 27658.6 41431.4 44117.0 44215.8 44259.6 44586.1 30701.2 36778.6 36963.6 37689.0 37701.4 36293.5 36379.5 36532.8 36718.1 37037.6 35332.5 36038.3 36264.5 37218.3 37300.0 31237.2 31862.4 32026.4 32237.0 32288.8 19634.7 24378.5 24675.0 24772.3 24854.9 23:47:30 INFO - PROCESS | 1667 | 23:47:30 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:34168.06 Median:20765.24 stddev:102417.43 (493.2%) stddev-sans-first:102673.33 23:47:30 INFO - PROCESS | 1667 | Values: 387.0 400.6 401.2 406.0 413.7 13519.0 17262.9 20013.7 20672.6 20688.8 15057.1 16340.0 20593.4 20771.7 20851.8 5631.5 5772.9 5935.5 5955.0 5959.8 4184.3 5847.7 5954.4 5956.5 6296.7 5835.6 6379.2 6552.8 6560.2 6568.3 6293.2 6450.8 6533.8 6540.5 6543.7 5605.1 5651.4 5652.3 5660.5 5665.6 23669.3 25627.7 25880.4 25982.2 26036.6 2214.3 2324.9 2347.1 2511.3 2890.7 14152.0 14730.4 14911.9 14926.3 15067.8 1545.0 1712.8 1727.4 1753.5 1781.0 96632.0 99242.5 100225.0 103169.5 104689.0 9330.4 10739.7 10996.7 11171.7 11177.3 14053.0 14154.6 14176.6 14187.5 14199.2 7837.6 8165.2 8167.8 8185.8 8759.6 7359.0 8114.5 8139.6 8145.7 8870.4 5608.3 6483.7 6572.6 6583.8 6589.9 1587.5 1708.8 1786.0 1870.7 1871.1 971.9 979.8 1012.2 1032.3 1081.4 17135.7 20080.8 20203.4 20316.8 20758.8 1018.2 1018.5 1039.2 1054.2 1065.6 11280.4 11325.9 11334.5 11363.0 11365.9 600416.0 605496.0 618358.3 637032.0 638915.0 60910.7 108914.5 110227.4 113553.0 123299.0 1014.8 1025.0 1049.0 1054.4 1055.6 17274.4 17614.3 17630.4 17658.5 17723.5 35355.0 48583.3 51063.2 52003.2 52579.0 71900.0 72389.3 73045.3 73688.3 73997.6 5477.4 6863.5 7595.0 8144.8 8316.4 9248.0 11937.3 12345.6 12464.6 12625.1 10735.9 10754.1 10841.3 10903.4 11456.3 10740.3 10931.7 11033.2 11175.5 11376.7 11289.0 11354.0 11543.7 11764.3 11800.3 8152.8 8280.9 8312.1 8315.9 8370.1 10501.8 11077.7 11347.0 11387.0 11412.8 23:47:30 INFO - PROCESS | 1667 | 23:47:30 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5715.33 Median:5033.17 stddev:3178.25 (63.1%) stddev-sans-first:3171.04 23:47:30 INFO - PROCESS | 1667 | Values: 1455.0 1584.7 1615.3 1838.4 1941.4 7976.7 8628.6 8782.4 8813.1 8817.4 7485.8 8742.9 8816.5 8856.8 8864.2 2450.6 2458.0 2478.1 2482.5 2483.5 2407.7 2489.8 2491.4 2493.4 2504.9 2235.8 2349.6 2443.1 2480.2 2492.8 2400.5 2425.1 2459.0 2472.6 2486.0 2435.2 2472.0 2484.1 2484.5 2484.7 8507.8 8964.1 9019.8 9027.2 9028.5 5434.5 5569.6 5580.0 5618.4 5623.9 3416.6 3475.7 3506.2 3512.9 3530.9 1020.8 1022.8 1027.2 1033.3 1033.6 12075.2 12179.7 12196.9 12206.2 12245.4 4580.7 4738.7 4754.6 4770.4 4776.1 9458.9 9535.0 9544.3 9556.6 9574.1 5242.5 5378.0 5422.8 5466.2 5483.8 4307.3 4448.2 4454.1 4455.4 4997.2 3639.7 3750.0 3916.0 3962.3 3966.2 1191.7 1217.1 1223.1 1225.4 1242.5 883.8 923.9 935.8 939.2 945.2 5856.7 5965.3 5981.8 6003.4 6045.3 933.3 997.2 1043.2 1051.4 1081.4 3823.2 3844.3 3945.1 3984.7 4040.8 10506.7 10835.0 10865.8 10986.9 11018.8 10699.3 10739.5 10796.5 10843.7 10851.2 8272.9 8353.2 8395.7 8424.6 8526.2 5630.9 5736.5 5877.5 5998.4 6018.8 9121.5 9495.4 9511.0 9519.0 9700.6 9551.0 9672.5 9808.3 9984.2 10011.8 7712.4 7738.3 7844.0 7845.2 7849.3 7278.6 7450.4 8070.0 8255.4 8457.2 7363.7 7431.4 7485.7 7495.7 7531.3 6283.3 6952.3 7267.7 7408.6 7413.3 6933.4 6994.3 7002.5 7047.4 7049.3 4901.6 4909.2 4942.7 5016.7 5051.6 4975.5 4983.4 4992.3 5049.7 5097.5 23:47:30 INFO - PROCESS | 1667 | 23:47:30 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:5712.07 Median:6442.31 stddev:1817.05 (28.2%) stddev-sans-first:1776.41 23:47:30 INFO - PROCESS | 1667 | Values: 314.8 317.3 326.4 330.5 331.5 6489.0 6491.1 6523.3 6546.5 6568.2 5229.7 6271.7 6354.1 6468.2 6480.2 4199.3 4730.2 4853.1 4901.8 4911.5 4912.4 4921.8 4923.2 4925.9 4935.6 4641.3 4735.3 4822.5 4863.2 4894.0 4773.3 4797.0 4820.0 4841.3 4863.1 3670.5 4043.9 4143.8 4150.9 4163.7 6290.1 6297.1 6297.3 6307.8 6313.7 5753.0 5769.3 5772.0 5772.2 5783.2 6753.9 6962.8 6988.4 6998.3 7008.3 4657.7 4701.7 4702.8 4706.6 4712.3 6416.9 6836.0 7341.0 7370.1 7385.4 4752.1 4758.3 4767.1 4773.8 4785.3 4838.5 4840.0 4846.1 4852.7 4861.6 4675.0 5361.5 5569.1 5734.8 5739.7 6955.3 7000.9 7005.8 7020.1 7038.3 1534.5 1587.5 1614.2 1616.9 1618.8 971.6 979.1 982.7 986.0 1014.1 6343.5 6406.3 6424.5 6436.3 6445.4 7158.0 7159.4 7160.3 7183.8 7196.6 5189.5 6423.1 6423.6 6430.6 6439.3 3676.8 3762.2 3788.3 3807.8 3809.2 7406.4 7462.5 7473.6 7496.0 7497.7 7338.5 7356.1 7358.1 7398.9 7401.3 7439.6 7440.2 7440.8 7447.8 7454.6 5682.9 7139.8 7140.4 7161.3 7365.0 7150.6 7222.1 7229.2 7238.1 7243.6 7654.4 7660.4 7670.5 7676.2 7676.8 4594.6 4647.3 4651.6 4654.4 4655.3 6179.9 6985.5 7215.9 7267.5 7282.3 6952.2 7029.9 7046.8 7047.6 7047.7 7021.3 7025.5 7030.3 7035.6 7040.3 6845.1 6874.8 6882.2 6890.1 6891.3 6797.7 7020.0 7049.5 7054.1 7071.9 6695.5 6709.7 6823.5 6824.1 6884.2 23:47:30 INFO - PROCESS | 1667 | 23:47:30 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:17211.91 Median:31108.59 stddev:23693.00 (76.2%) stddev-sans-first:23726.79 23:47:30 INFO - PROCESS | 1667 | Values: 639.3 677.3 737.3 741.8 745.5 14403.4 14728.0 14765.4 14951.1 14996.6 11439.9 13626.3 13656.7 13727.0 13789.9 714.5 729.8 900.1 918.6 921.4 917.1 920.6 921.1 922.7 926.8 747.6 968.5 969.2 969.6 972.7 946.5 957.2 959.2 963.2 965.1 713.0 715.7 715.8 716.6 718.4 14797.3 14831.9 14839.3 14839.6 14886.3 12241.7 12363.9 12464.5 12566.6 12979.6 9965.0 10043.2 10081.8 10105.7 10693.4 3390.3 3467.5 3470.7 3470.8 3492.6 74152.3 74723.6 74847.0 75003.1 75266.6 8210.6 9086.5 9368.3 9439.8 9490.0 21065.6 21080.9 21082.6 21099.0 21178.6 7251.3 7401.9 7468.2 7491.5 7512.8 4002.2 4013.6 4077.0 4103.8 4139.5 3394.7 3459.5 3464.2 3466.9 3469.7 586.7 605.8 613.9 614.8 627.0 498.9 503.9 504.4 583.5 608.7 8086.1 8123.8 8203.0 8281.3 8293.1 597.5 610.4 612.1 616.8 618.7 3768.3 3804.8 3813.5 3881.9 3882.0 85789.0 87337.6 87782.0 87806.0 88089.0 66929.0 69976.0 70327.0 70458.9 71753.0 64952.0 65247.4 65993.0 66133.7 66199.7 7565.9 7619.5 7647.7 7651.3 7666.0 45828.8 46180.8 46184.1 46463.6 46788.8 58798.0 61397.7 61600.4 62321.0 62525.7 9922.8 9957.3 10029.3 10115.6 10236.0 8549.6 10770.5 10922.1 10935.0 11512.7 11217.0 11267.8 11627.8 11639.6 11848.2 11046.9 11128.3 11308.4 11521.3 12015.0 10733.7 10833.8 10950.2 10978.7 11070.4 9375.0 9481.0 9486.4 9764.3 9931.7 9253.6 9577.3 9588.0 9610.6 9764.8 23:47:30 INFO - PROCESS | 1667 | -------- Summary: end -------- 23:47:30 INFO - PROCESS | 1667 | 23:47:31 INFO - TEST-INFO | 1667: exit 0 23:47:31 INFO - TEST-OK | dromaeo_css | took 1114907ms 23:47:31 INFO - TEST-START | kraken 23:47:31 INFO - operating with platform_type : mac_ 23:47:31 INFO - Initialising browser for kraken test... 23:47:31 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmp3y0ace/profile http://localhost:49274/getInfo.html) 23:47:33 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 23:47:33 INFO - PROCESS | 1675 | colorDepth:24 23:47:33 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 23:47:33 INFO - PROCESS | 1675 | __metrics 23:47:33 INFO - PROCESS | 1675 | 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" 23:47:33 ERROR - PROCESS | 1675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:47:34 INFO - TEST-INFO | 1675: exit 0 23:47:34 INFO - Browser initialized. 23:47:34 INFO - Running cycle 1/1 for kraken test... 23:47:34 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmp3y0ace/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:47:46 INFO - PROCESS | 1677 | RSS: Main: 211660800 23:47:46 INFO - PROCESS | 1677 | 23:47:48 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 23:47:49 INFO - PROCESS | 1677 | RSS: Main: 272326656 23:47:49 INFO - PROCESS | 1677 | 23:47:52 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 23:47:52 INFO - PROCESS | 1677 | RSS: Main: 286404608 23:47:52 INFO - PROCESS | 1677 | 23:47:56 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 23:47:56 INFO - PROCESS | 1677 | RSS: Main: 371826688 23:47:56 INFO - PROCESS | 1677 | 23:47:58 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 23:47:59 INFO - PROCESS | 1677 | RSS: Main: 279203840 23:47:59 INFO - PROCESS | 1677 | 23:48:01 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:48:02 INFO - PROCESS | 1677 | RSS: Main: 280522752 23:48:02 INFO - PROCESS | 1677 | 23:48:04 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 23:48:05 INFO - PROCESS | 1677 | RSS: Main: 318656512 23:48:05 INFO - PROCESS | 1677 | 23:48:08 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 23:48:09 INFO - PROCESS | 1677 | RSS: Main: 319250432 23:48:09 INFO - PROCESS | 1677 | 23:48:12 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 23:48:12 INFO - PROCESS | 1677 | RSS: Main: 318758912 23:48:12 INFO - PROCESS | 1677 | 23:48:14 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:48:14 INFO - PROCESS | 1677 | RSS: Main: 309866496 23:48:14 INFO - PROCESS | 1677 | 23:48:16 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:48:17 INFO - PROCESS | 1677 | RSS: Main: 317108224 23:48:17 INFO - PROCESS | 1677 | 23:48:19 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:48:20 INFO - PROCESS | 1677 | RSS: Main: 319123456 23:48:20 INFO - PROCESS | 1677 | 23:48:23 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:48:23 INFO - PROCESS | 1677 | RSS: Main: 324497408 23:48:23 INFO - PROCESS | 1677 | 23:48:26 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:48:27 INFO - PROCESS | 1677 | RSS: Main: 322461696 23:48:27 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 23:48:29 INFO - PROCESS | 1677 | RSS: Main: 386371584 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | __start_tp_report 23:48:29 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 23:48:29 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 23:48:29 INFO - PROCESS | 1677 | |i|pagename|runs| 23:48:29 INFO - PROCESS | 1677 | |0;ai-astar;113;113;116;113;112;113;115;112;113;132 23:48:29 INFO - PROCESS | 1677 | |1;audio-beat-detection;172;146;174;165;119;141;144;120;168;118 23:48:29 INFO - PROCESS | 1677 | |2;audio-dft;184;190;184;186;184;187;183;185;186;187 23:48:29 INFO - PROCESS | 1677 | |3;audio-fft;74;75;97;185;78;56;56;60;128;56 23:48:29 INFO - PROCESS | 1677 | |4;audio-oscillator;93;92;140;76;79;78;123;79;77;125 23:48:29 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;88;85;89;85;131;85;88;86;89;84 23:48:29 INFO - PROCESS | 1677 | |6;imaging-darkroom;134;129;95;95;92;106;150;107;99;149 23:48:29 INFO - PROCESS | 1677 | |7;imaging-desaturate;84;84;84;84;84;84;86;84;87;84 23:48:29 INFO - PROCESS | 1677 | |8;json-parse-financial;59;66;64;63;64;63;66;84;60;58 23:48:29 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;63;66;64;70;57;72;96;95;97;90 23:48:29 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;80;92;105;102;103;93;80;80;80;84 23:48:29 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;132;130;128;131;128;141;140;117;150;145 23:48:29 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;166;169;176;165;167;165;162;164;165;164 23:48:29 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;68;83;94;96;96;89;66;70;66;66 23:48:29 INFO - PROCESS | 1677 | __end_tp_report 23:48:29 INFO - PROCESS | 1677 | __start_cc_report 23:48:29 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,2009 23:48:29 INFO - PROCESS | 1677 | __end_cc_report 23:48:29 INFO - PROCESS | 1677 | __startTimestamp1464936509372__endTimestamp 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | ------- Summary: start ------- 23:48:29 INFO - PROCESS | 1677 | Number of tests: 14 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:115.20 Median:113.00 stddev:6.03 (5.3%) stddev-sans-first:6.35 23:48:29 INFO - PROCESS | 1677 | Values: 113.0 113.0 116.0 113.0 112.0 113.0 115.0 112.0 113.0 132.0 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:146.70 Median:155.50 stddev:22.41 (14.4%) stddev-sans-first:21.81 23:48:29 INFO - PROCESS | 1677 | Values: 172.0 146.0 174.0 165.0 119.0 141.0 144.0 120.0 168.0 118.0 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:185.60 Median:186.00 stddev:2.07 (1.1%) stddev-sans-first:2.11 23:48:29 INFO - PROCESS | 1677 | Values: 184.0 190.0 184.0 186.0 184.0 187.0 183.0 185.0 186.0 187.0 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:86.50 Median:67.00 stddev:41.35 (61.7%) stddev-sans-first:43.61 23:48:29 INFO - PROCESS | 1677 | Values: 74.0 75.0 97.0 185.0 78.0 56.0 56.0 60.0 128.0 56.0 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:78.50 stddev:24.02 (30.6%) stddev-sans-first:25.45 23:48:29 INFO - PROCESS | 1677 | Values: 93.0 92.0 140.0 76.0 79.0 78.0 123.0 79.0 77.0 125.0 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:91.00 Median:87.00 stddev:14.17 (16.3%) stddev-sans-first:14.99 23:48:29 INFO - PROCESS | 1677 | Values: 88.0 85.0 89.0 85.0 131.0 85.0 88.0 86.0 89.0 84.0 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:115.60 Median:121.00 stddev:22.77 (18.8%) stddev-sans-first:23.15 23:48:29 INFO - PROCESS | 1677 | Values: 134.0 129.0 95.0 95.0 92.0 106.0 150.0 107.0 99.0 149.0 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:84.00 stddev:1.08 (1.3%) stddev-sans-first:1.13 23:48:29 INFO - PROCESS | 1677 | Values: 84.0 84.0 84.0 84.0 84.0 84.0 86.0 84.0 87.0 84.0 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:64.00 stddev:7.32 (11.4%) stddev-sans-first:7.47 23:48:29 INFO - PROCESS | 1677 | Values: 59.0 66.0 64.0 63.0 64.0 63.0 66.0 84.0 60.0 58.0 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:77.00 Median:81.00 stddev:15.68 (19.4%) stddev-sans-first:15.80 23:48:29 INFO - PROCESS | 1677 | Values: 63.0 66.0 64.0 70.0 57.0 72.0 96.0 95.0 97.0 90.0 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:80.00 stddev:10.47 (13.1%) stddev-sans-first:10.48 23:48:29 INFO - PROCESS | 1677 | Values: 80.0 92.0 105.0 102.0 103.0 93.0 80.0 80.0 80.0 84.0 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:134.20 Median:136.00 stddev:9.73 (7.2%) stddev-sans-first:10.28 23:48:29 INFO - PROCESS | 1677 | Values: 132.0 130.0 128.0 131.0 128.0 141.0 140.0 117.0 150.0 145.0 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:165.50 stddev:3.89 (2.3%) stddev-sans-first:4.12 23:48:29 INFO - PROCESS | 1677 | Values: 166.0 169.0 176.0 165.0 167.0 165.0 162.0 164.0 165.0 164.0 23:48:29 INFO - PROCESS | 1677 | 23:48:29 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.40 Median:86.00 stddev:13.44 (15.6%) stddev-sans-first:13.61 23:48:29 INFO - PROCESS | 1677 | Values: 68.0 83.0 94.0 96.0 96.0 89.0 66.0 70.0 66.0 66.0 23:48:29 INFO - PROCESS | 1677 | -------- Summary: end -------- 23:48:29 INFO - PROCESS | 1677 | 23:48:30 INFO - TEST-INFO | 1677: exit 0 23:48:30 INFO - TEST-OK | kraken | took 58740ms 23:48:30 INFO - SUITE-END | took 1173s 23:48:30 INFO - Completed test suite (00:19:34) 23:48:30 INFO - javascript benchmark 23:48:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3346.4645656694934, 3431.9142021449443, 3438.1748380388713, 3445.758796884219, 3495.9300813983723, 5591.30108736408, 5670.404607516213, 5679.602427830054, 5687.232223649801, 5688.118341657045, 5675.801346952858, 5698.145278208271, 5720.856978575538, 5721.941440833439, 5729.054705973509, 5121.078205922932, 5173.146430838917, 5176.689398636075, 5285.445028272034, 5294.391145018314, 5231.790728370861, 5262.578993680516, 5274.657147285414, 5275.683458992454, 5280.471952804708, 5200.947990520103, 5241.580673546107, 5251.658642188265, 5259.158534634459, 5260.0, 5208.427073021983, 5241.947580524184, 5247.711375874325, 5268.631195816294, 5298.735063246822, 5098.133317336062, 5138.7173705446185, 5150.381954165487, 5201.271821944931, 5201.817936372209, 5495.340559132889, 5528.308961379827, 5541.974734674058, 5556.111022236463, 5563.749631266603, 6226.501879849647, 6247.937520624827, 6255.499560035232, 6266.749330026793, 6268.49852011838, 6093.542984276197, 6180.783672571483, 6310.495160387204, 6316.936830631681, 6320.399562041599, 5803.245578074823, 5818.389069147721, 5824.301083869963, 5825.737841797045, 5858.912116318259, 6329.398707122816, 6343.302236753953, 6343.904841427382, 6347.968260158762, 6352.2694890087305, 5989.580729348991, 6095.238095238095, 6101.511879049665, 6133.969330153321, 6185.752569897244, 6134.467201660938, 6175.135481032659, 6200.937990620127, 6204.534659900435, 6206.131141640174, 6114.753609057711, 6231.688415579203, 6242.500599951993, 6246.562740608114, 6252.374762523802, 5183.300254465675, 6213.937860621336, 6244.094606282064, 6262.906056409158, 6286.308506064329, 4815.133276010281, 4876.756162191876, 4917.803287868445, 4925.704457732548, 4925.729084900365, 4372.213001659668, 4402.911941761146, 4431.0, 4444.2222611042935, 4453.554644535585, 5672.262605861211, 5713.914291285634, 5715.828525144252, 5725.0, 5740.196372507852, 6120.812855101226, 6240.968795155995, 6246.593971391845, 6338.144350512622, 6356.14192084072, 5649.209110724501, 5655.688937108499, 5668.433156684298, 5685.829425117253, 5687.800926967537, 5310.097283461743, 5342.492463215988, 5356.7857285708915, 5384.246205531229, 5387.111126664056, 6296.43332100104, 6312.873742525216, 6322.936770632235, 6326.4306212442025, 6339.461145802658, 6138.081874381997, 6214.0, 6262.46769024638, 6310.5898116622275, 6337.397947195009, 6258.3428739983465, 6297.811065668037, 6313.810585682437, 6317.30509643948, 6331.366863313632, 6088.360722124113, 6151.907721384094, 6161.476274516537, 6164.137020816999, 6167.537434692326, 6121.635889161253, 6223.159873417031, 6282.528810339334, 6297.086922396318, 6308.842278943063, 5842.883142337215, 6134.570580059353, 6142.437386316015, 6148.077788331786, 6164.7842325518395, 5691.886162276648, 6124.5712800103565, 6128.8774224516155, 6138.1713468681155, 6142.846428839136, 4846.321514987975, 5883.0881213411985, 5886.20536227621, 5949.107633854784, 5981.1327357532045, 6082.939170608237, 6107.175531303395, 6116.5718399711595, 6120.791457599439, 6147.385261473817, 5855.707214639337, 5942.346341902301, 5959.255093113361, 5967.641941483351, 5988.970055149871], "value": 5692.079997315554, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.87124412030806, 529.2299703930781, 538.8733647592813, 540.2895192821439, 575.5827025406578, 30312.39375212497, 31583.684163158363, 31600.20999475014, 31642.367152656934, 38274.234515309676, 43899.0, 43945.0, 44136.99999999992, 44160.675179744656, 44218.557814421845, 9481.71554853352, 9753.21974242059, 9758.0, 9761.219102471821, 9765.609375625001, 8598.355123365773, 8775.517346545937, 8795.604197811082, 9488.288378371615, 9634.181094606967, 11477.024452921512, 11655.533778648844, 11659.358735269558, 11668.1832271741, 11669.358185299847, 11265.324080555192, 11369.772604547905, 11375.943120284428, 11384.829227561593, 11388.943055284753, 8619.870701939475, 11328.943355283252, 11359.204855660024, 11452.599159029394, 11555.19113662044, 31986.0, 38442.80778596089, 38993.22013559741, 39013.0, 39031.0, 3069.2633767895545, 3105.316830297331, 3318.950215746766, 3401.5918089829133, 3573.4818451324672, 27081.1875643731, 27469.725302746916, 27703.861480692467, 28272.43455130886, 28619.0, 2729.8089133760445, 2757.5174344489633, 2764.5853122031654, 2767.764739997103, 2811.2550174203852, 319551.0, 321584.0, 322263.38868305745, 323329.0, 323606.0, 35967.28065438729, 36645.63354366422, 36650.63349366526, 36694.449583256275, 36711.449328260096, 35184.94445166649, 35398.0, 35406.0, 35450.64549354525, 35484.29031419409, 14485.78271325931, 15516.069035857885, 15758.369665213264, 15772.132532710582, 15773.526794196192, 10516.737081573021, 13322.333883305795, 13328.800067998987, 13787.793183102262, 13798.724025519634, 7791.610419479003, 7896.881546776803, 8175.918240817516, 8215.219554142346, 8331.8333633327, 2923.0, 3344.816035118043, 3411.164264755101, 3418.418868792311, 3448.1724386147143, 926.3422969691594, 930.316217580078, 1044.081208536483, 1085.0776839686362, 1113.1373185781085, 25776.0, 28663.0, 29060.564091538643, 29353.11940641784, 29396.706032939826, 808.6482380164647, 1049.9395610433364, 1113.9721506962228, 1199.208522375228, 1296.3259105265208, 12543.811842822364, 13507.864921350858, 14757.040792348464, 14920.253987300592, 15197.316120774592, 624007.0, 633026.0, 633106.0, 634201.0, 634752.0, 58674.41325586689, 60780.392196078355, 60867.695661522295, 60871.391286087455, 60896.173114806625, 1365.2286458151113, 1422.2888555722138, 1471.4040813470563, 1495.1776522912355, 1507.4347119830063, 28363.290917727223, 28367.858160709064, 28804.135875923756, 29419.705802941695, 29471.85264073666, 46940.0, 47239.055218896116, 47460.0, 47740.7612961933, 47857.28214076861, 362438.0, 364960.0, 365152.0, 365215.0, 623323.0, 26522.336941576614, 27306.180814575593, 27587.586186206823, 27607.723922760517, 27658.5851212236, 41431.37852932148, 44117.0, 44215.778921105186, 44259.55740442554, 44586.10827783491, 30701.23246918845, 36778.63221367752, 36963.63036369602, 37689.0, 37701.434478483396, 36293.455598166576, 36379.45430818593, 36532.81733591315, 36718.082047949014, 37037.62962370342, 35332.47001295034, 36038.27923441569, 36264.45603316005, 37218.255634886606, 37300.0, 31237.219069523442, 31862.362752744353, 32026.35947280995, 32237.0, 32288.83855580707, 19634.705479418106, 24378.51242975166, 24675.0, 24772.25683229506, 24854.88153033082], "value": 17902.34832902654, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.97097717671176, 400.5753900865083, 401.2436557089886, 405.98376064957404, 413.72280572016746, 13519.0, 17262.913685431588, 20013.699794503074, 20672.586548269024, 20688.793112068874, 15057.096574205556, 16340.0, 20593.38219853403, 20771.68842467364, 20851.791482085173, 5631.549476041927, 5772.884542309152, 5935.495482883938, 5955.0, 5959.761609535613, 4184.288670925935, 5847.736851841656, 5954.374790646984, 5956.52347812174, 6296.716647750839, 5835.562332825034, 6379.170707808002, 6552.770653027145, 6560.179977502812, 6568.277489476154, 6293.213348331458, 6450.774222902222, 6533.803985880431, 6540.542162048659, 6543.672816359163, 5605.131204663284, 5651.434856514356, 5652.349979752299, 5660.518855897212, 5665.575081868877, 23669.28992130239, 25627.74372256272, 25880.352991175183, 25982.22053338403, 26036.609450858254, 2214.280358883353, 2324.8721320327377, 2347.084636991575, 2511.2717311979504, 2890.682024977251, 14152.0, 14730.410783568645, 14911.850881491155, 14926.328315225841, 15067.773983390258, 1544.9536513904488, 1712.7516510105966, 1727.360876475712, 1753.4739578126512, 1780.97328540072, 96632.0, 99242.5037874806, 100225.0, 103169.48415257876, 104689.0, 9330.393524420891, 10739.731506712238, 10996.725081873017, 11171.727466203032, 11177.32936023825, 14053.016288859682, 14154.646133846736, 14176.645583860485, 14187.503437379642, 14199.21904295253, 7837.629819177694, 8165.183481651906, 8167.754967350989, 8185.836283274421, 8759.562021899006, 7359.0, 8114.513129212266, 8139.552324622085, 8145.714899978472, 8870.379073464925, 5608.298962629671, 6483.740650374027, 6572.572782769104, 6583.80248592543, 6589.86820263592, 1587.5078725594872, 1708.7611481675685, 1785.9821401786076, 1870.6819840627127, 1871.0644677661169, 971.9465429401309, 979.8089372572423, 1012.1700205831148, 1032.256775121911, 1081.3619964221148, 17135.742963855304, 20080.799192007893, 20203.39389818308, 20316.796832031785, 20758.792412075687, 1018.2159737002466, 1018.4653057145058, 1039.2465462539599, 1054.225144518778, 1065.5684447798653, 11280.435978201058, 11325.886741132483, 11334.546618135348, 11363.0, 11365.886341136647, 600416.0, 605496.0, 618358.2656693772, 637032.0, 638915.0, 60910.695446522484, 108914.45542772394, 110227.44886275518, 113553.0, 123299.0, 1014.832552628808, 1024.9692509224735, 1048.9580416783242, 1054.446415631784, 1055.551390658967, 17274.395396161075, 17614.29542818273, 17630.382936597158, 17658.470245892644, 17723.46829595114, 35355.0, 48583.27125093197, 51063.234051488515, 52003.21995170151, 52579.0, 71900.0, 72389.27610723826, 73045.26954730597, 73688.26311736814, 73997.6300118496, 5477.385188824819, 6863.485238607024, 7595.012648355679, 8144.75565733029, 8316.41785075039, 9248.0, 11937.28376297425, 12345.629631111082, 12464.626061218178, 12625.116241862981, 10735.89264107349, 10754.13966882632, 10841.321298520794, 10903.369581941655, 11456.312621242752, 10740.30188037775, 10931.672049838518, 11033.227674062739, 11175.497102630563, 11376.715582110513, 11289.0, 11354.0, 11543.711407214887, 11764.294142351486, 11800.291982481078, 8152.8369432609825, 8280.917190828015, 8312.127226641116, 8315.875261870955, 8370.079291278076, 10501.78996420052, 11077.723056923642, 11347.0, 11387.0, 11412.82880756806], "value": 9569.86104044491, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1454.9960527236199, 1584.7305957987141, 1615.3215649427239, 1838.3841413126602, 1941.4175747275813, 7976.720814771485, 8628.56857157142, 8782.38523303369, 8813.074627164151, 8817.426867253633, 7485.7754267372065, 8742.912570874289, 8816.4710117393, 8856.77858053549, 8864.202221800037, 2450.5956517174645, 2457.9508409831883, 2478.0954951442773, 2482.515909397668, 2483.50329934013, 2407.711074671033, 2489.8132640051977, 2491.364702000987, 2493.4389762303517, 2504.9373765655914, 2235.8099561537288, 2349.5535848188874, 2443.0716327795412, 2480.231128350208, 2492.8379655322437, 2400.50789588134, 2425.1269542964433, 2459.004103338151, 2472.5796614575474, 2486.0, 2435.1963851929, 2472.0235506974823, 2484.093305943318, 2484.5155194737035, 2484.689413823272, 8507.829843403159, 8964.103589641049, 9019.774505637346, 9027.187553120251, 9028.458292502406, 5434.456554344534, 5569.554435645138, 5580.0, 5618.381977982419, 5623.915641265385, 3416.5558477398026, 3475.7393195510185, 3506.1760486285702, 3512.8946131616094, 3530.9293814123575, 1020.7958408318366, 1022.7954409118132, 1027.2193133218732, 1033.3283365812283, 1033.6175615022414, 12075.215111017844, 12179.695507612381, 12196.878031219663, 12206.206596571194, 12245.387730613435, 4580.679352445363, 4738.668293219511, 4754.572088512051, 4770.356001939763, 4776.09254241693, 9458.905410945803, 9535.0, 9544.284178686627, 9556.61773529051, 9574.138327550554, 5242.501962313574, 5377.973110134424, 5422.75597598109, 5466.207399926989, 5483.8080667177255, 4307.310830267143, 4448.243798554254, 4454.10917816438, 4455.420795296591, 4997.200447928314, 3639.727020473475, 3749.962500374961, 3916.0, 3962.3462128748433, 3966.186931678999, 1191.6544202181271, 1217.1419149499582, 1223.1499108119772, 1225.350564200975, 1242.5092088624942, 883.8497455432463, 923.9260859131253, 935.8021732182817, 939.196986576481, 945.2154711589365, 5856.677882716406, 5965.3139888912465, 5981.833542459266, 6003.369646187175, 6045.33501314855, 933.3280038372361, 997.2072202598952, 1043.2019505078622, 1051.363924825485, 1081.3563383656797, 3823.235352929425, 3844.2695887781233, 3945.0926286953586, 3984.6812255019527, 4040.8383664653675, 10506.737331566772, 10835.0, 10865.83701244482, 10986.890131098746, 11018.834717479243, 10699.304545204537, 10739.463026848627, 10796.51415686296, 10843.674689759162, 10851.240413171161, 8272.875906861402, 8353.20644538768, 8395.70615028471, 8424.620892059873, 8526.23263906239, 5630.85922851932, 5736.541076713769, 5877.500412464989, 5998.400159983967, 6018.789342372996, 9121.498317592595, 9495.38280011797, 9511.048895110434, 9519.000504947158, 9700.563474643517, 9550.997145299922, 9672.516374181121, 9808.313418060667, 9984.151347135285, 10011.849822252527, 7712.38300935935, 7738.303552680286, 7843.960780196063, 7845.176256493214, 7849.332806711265, 7278.636068196463, 7450.403967682464, 8070.0, 8255.390198911155, 8457.154284571494, 7363.705451782083, 7431.442641801778, 7485.737999170003, 7495.737649182471, 7531.322181003517, 6283.340249273943, 6952.270011648704, 7267.709291628275, 7408.555486670817, 7413.332800048021, 6933.445324373937, 6994.300569942965, 7002.509824312249, 7047.40097091753, 7049.330313620198, 4901.5588597026435, 4909.239067944474, 4942.728149951788, 5016.673916195436, 5051.595872330278, 4975.477574854733, 4983.377077865267, 4992.2511623256805, 5049.671771334852, 5097.464766199496], "value": 4617.824369840646, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.7639270547088, 317.303518776286, 326.4352669881106, 330.48444426694374, 331.50771104909205, 6489.0, 6491.091247225392, 6523.34766523348, 6546.541742078057, 6568.1789776277965, 5229.712365819878, 6271.655058971754, 6354.0937718684445, 6468.159139311882, 6480.222373315209, 4199.307114326132, 4730.172219861528, 4853.102176097415, 4901.8039278429, 4911.459739428669, 4912.38595175603, 4921.7785199666105, 4923.212286034235, 4925.926111108336, 4935.580475659555, 4641.327007583898, 4735.3133795599615, 4822.5177482251665, 4863.2461968394955, 4894.04566109607, 4773.284007398918, 4796.952030479721, 4820.0, 4841.298011788306, 4863.10032643962, 3670.4861319415304, 4043.858464953742, 4143.751374917484, 4150.8547200847825, 4163.6669066474915, 6290.119383286344, 6297.0554416837385, 6297.307296197415, 6307.810765676991, 6313.71588278524, 5753.0, 5769.278840144982, 5771.971140144315, 5772.2496075510335, 5783.248177736868, 6753.85184518633, 6962.756303529402, 6988.440924726061, 6998.265182155851, 7008.334208250208, 4657.69724967876, 4701.717896926195, 4702.788374523155, 4706.599939005188, 4712.340272361837, 6416.871662566769, 6836.008778727107, 7340.963295183543, 7370.078740157481, 7385.409167266552, 4752.144613969519, 4758.262469317261, 4767.141914455362, 4773.785179666923, 4785.258284965836, 4838.516148385133, 4839.975800120977, 4846.079244943449, 4852.708837469763, 4861.565838077776, 4674.953250467452, 5361.544268737123, 5569.10894256917, 5734.827955161351, 5739.741711622987, 6955.339242771928, 7000.879859222602, 7005.824854378681, 7020.052292940388, 7038.331358520933, 1534.5242974677885, 1587.5475489485673, 1614.2171047042175, 1616.8706503479693, 1618.8381161883717, 971.5676523946917, 979.0747743382577, 982.6609819612222, 986.0090608938115, 1014.0569270578286, 6343.52423568225, 6406.2953075161695, 6424.453921416639, 6436.292007879129, 6445.355464453517, 7157.964210178916, 7159.391451726661, 7160.283971602798, 7183.784486465414, 7196.64016799158, 5189.455107213764, 6423.068655044993, 6423.646699431576, 6430.614163150132, 6439.259485159173, 3676.8400052679503, 3762.191128907298, 3788.337041017866, 3807.8476860925807, 3809.1619843634357, 7406.370458510979, 7462.514936529108, 7473.626318684044, 7495.962520187473, 7497.700091996368, 7338.522996005243, 7356.117265928123, 7358.11702595692, 7398.889016664754, 7401.333879950723, 7439.55362678241, 7440.181580026193, 7440.7767766966, 7447.776566703008, 7454.627268636546, 5682.857928551819, 7139.750108746169, 7140.428765698628, 7161.283871612797, 7364.968904353502, 7150.64246787669, 7222.06113205301, 7229.168645605612, 7238.0952380952385, 7243.5653860768525, 7654.425918056279, 7660.425468089804, 7670.463067585439, 7676.193999630182, 7676.80807979805, 4594.609458196091, 4647.349371088118, 4651.604613607881, 4654.37165982588, 4655.2784318430695, 6179.938200617937, 6985.476089293222, 7215.891761623475, 7267.491275610657, 7282.271772822675, 6952.165740111219, 7029.929700703132, 7046.753363632249, 7047.647617618996, 7047.7180912762915, 7021.262767409553, 7025.508214424941, 7030.3321184487395, 7035.577865328097, 7040.331168538981, 6845.110135682329, 6874.793756187322, 6882.242953275236, 6890.138732658418, 6891.345322194384, 6797.660116994031, 7020.0, 7049.541779784297, 7054.08296921397, 7071.858562828817, 6695.464362850863, 6709.664516774044, 6823.454123669995, 6824.146981627297, 6884.173899131936], "value": 5094.955982892176, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [639.280809089774, 677.2956125629346, 737.2701025984277, 741.766343601765, 745.4707157917875, 14403.423863045466, 14728.042677225987, 14765.409383624656, 14951.102933823964, 14996.625084372896, 11439.885601143986, 13626.25055621943, 13656.726865462684, 13727.0, 13789.89680825534, 714.4891402647094, 729.806601250669, 900.0774206438421, 918.6325469812095, 921.4195057114009, 917.1149840404008, 920.645551462685, 921.1295325917011, 922.6816748221853, 926.8378033844084, 747.5664114813395, 968.4576637083256, 969.1519920069939, 969.6073090398364, 972.7033254857266, 946.507815935713, 957.2437774158407, 959.2086528613921, 963.1957315641378, 965.1024547171128, 713.0373995106617, 715.7316006497564, 715.8103102677794, 716.5843810589845, 718.3929579505362, 14797.260136993107, 14831.851681483264, 14839.25803709821, 14839.629009274746, 14886.255687215596, 12241.693957651041, 12363.913736911434, 12464.501419943192, 12566.623001309885, 12979.610611681665, 9964.950175249078, 10043.19654427644, 10081.798364032751, 10105.696829095139, 10693.358398496115, 3390.2880395117054, 3467.4625433057918, 3470.715835140989, 3470.843812028465, 3492.6158122606494, 74152.25847741561, 74723.6263818683, 74847.0, 75003.12492187685, 75266.62366688186, 8210.630521626541, 9086.500242486594, 9368.34421590482, 9439.764005899908, 9490.0, 21065.578688426147, 21080.945952702612, 21082.578348432944, 21099.0, 21178.576428471344, 7251.311125443074, 7401.888971665429, 7468.178500364956, 7491.475596708287, 7512.805353656343, 4002.1595464952684, 4013.57857424972, 4076.979615101965, 4103.7742924138865, 4139.523954745183, 3394.694477496988, 3459.5329630500064, 3464.1512829356466, 3466.8613255470004, 3469.7224222062678, 586.6802592587051, 605.7516418268577, 613.8526753579198, 614.781752477864, 626.9749210031548, 498.94012718474016, 503.9092963266574, 504.3519077984772, 583.5127668396937, 608.7321578505444, 8086.070101938352, 8123.79690507742, 8203.0, 8281.321053604694, 8293.087760346358, 597.5339235396398, 610.3713175429315, 612.0788213738384, 616.7964571691331, 618.6875627807929, 3768.2840260350445, 3804.7907365095184, 3813.4851795007876, 3881.8835434936996, 3881.9805900970314, 85789.0, 87337.56331218303, 87782.0, 87806.0, 88089.0, 66929.0, 69976.0, 70327.0, 70458.9431158533, 71753.0, 64952.02571961424, 65247.36881577904, 65993.0, 66133.66933165303, 66199.66900165565, 7565.886511702219, 7619.504732192389, 7647.732329368668, 7651.273129052731, 7666.003419555422, 45828.77085614684, 46180.845478863295, 46184.076318474115, 46463.6060918173, 46788.7660561695, 58798.0, 61397.693011534655, 61600.383996159464, 62321.0, 62525.68737156285, 9922.751931201778, 9957.253206009724, 10029.348092373972, 10115.595376185165, 10236.0, 8549.586510365687, 10770.51532681047, 10922.126229901747, 10935.015848573667, 11512.654620361403, 11217.0, 11267.774644507228, 11627.825582616437, 11639.59261425846, 11848.170628056298, 11046.889531104585, 11128.268941495298, 11308.378039207922, 11521.251118677259, 12015.038796896402, 10733.677989660322, 10833.837492437777, 10950.178736594946, 10978.725531861768, 11070.446477676245, 9375.0, 9480.95259523698, 9486.430814151174, 9764.267679924176, 9931.65239216624, 9253.629854805733, 9577.329586928849, 9588.0, 9610.567524461556, 9764.755881102745], "value": 6688.335500936863, "unit": "score"}], "name": "dromaeo_css", "value": 7317.051176481284, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 116.0, 113.0, 112.0, 113.0, 115.0, 112.0, 113.0, 132.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 146.0, 174.0, 165.0, 119.0, 141.0, 144.0, 120.0, 168.0, 118.0], "value": 146.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 190.0, 184.0, 186.0, 184.0, 187.0, 183.0, 185.0, 186.0, 187.0], "value": 185.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 97.0, 185.0, 78.0, 56.0, 56.0, 60.0, 128.0, 56.0], "value": 86.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 140.0, 76.0, 79.0, 78.0, 123.0, 79.0, 77.0, 125.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 85.0, 131.0, 85.0, 88.0, 86.0, 89.0, 84.0], "value": 91.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 129.0, 95.0, 95.0, 92.0, 106.0, 150.0, 107.0, 99.0, 149.0], "value": 115.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 84.0, 84.0, 86.0, 84.0, 87.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 66.0, 64.0, 63.0, 64.0, 63.0, 66.0, 84.0, 60.0, 58.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 64.0, 70.0, 57.0, 72.0, 96.0, 95.0, 97.0, 90.0], "value": 77.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 92.0, 105.0, 102.0, 103.0, 93.0, 80.0, 80.0, 80.0, 84.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 130.0, 128.0, 131.0, 128.0, 141.0, 140.0, 117.0, 150.0, 145.0], "value": 134.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 169.0, 176.0, 165.0, 167.0, 165.0, 162.0, 164.0, 165.0, 164.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 83.0, 94.0, 96.0, 96.0, 89.0, 66.0, 70.0, 66.0, 66.0], "value": 79.4, "unit": "score"}], "name": "kraken", "value": 1532.8000000000002, "alertThreshold": 2.0}]} 23:48:30 INFO - Return code: 0 23:48:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:48:30 ERROR - # TBPL SUCCESS # 23:48:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:48:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:48:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:48:30 INFO - 2016-06-02 23:48:30.685 screenresolution[1681:10600] starting screenresolution argv=screenresolution get 23:48:30 INFO - 2016-06-02 23:48:30.688 screenresolution[1681:10600] Display 0: 1600x1200x32@60 23:48:30 INFO - 2016-06-02 23:48:30.694 screenresolution[1682:10603] starting screenresolution argv=screenresolution list 23:48:30 INFO - Available Modes on Display 0 23:48:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:48:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:48:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:48:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:48:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:48:30 INFO - Intel Iris: 23:48:30 INFO - Chipset Model: Intel Iris 23:48:30 INFO - Type: GPU 23:48:30 INFO - Bus: Built-In 23:48:30 INFO - VRAM (Dynamic, Max): 1536 MB 23:48:30 INFO - Vendor: Intel (0x8086) 23:48:30 INFO - Device ID: 0x0a2e 23:48:30 INFO - Revision ID: 0x0009 23:48:30 INFO - Displays: 23:48:30 INFO - Display: 23:48:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:48:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:48:30 INFO - Display Serial Number: 1600x1200 60 23:48:30 INFO - Main Display: Yes 23:48:30 INFO - Mirror: Off 23:48:30 INFO - Online: Yes 23:48:30 INFO - Rotation: Supported 23:48:30 INFO - Return code: 0 23:48:30 INFO - Running post-action listener: _resource_record_post_action 23:48:30 INFO - Running post-run listener: _resource_record_post_run 23:48:31 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 31702528; Write bytes: 655252480; Read time: 200; Write time: 1880 23:48:31 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 349131264; Write bytes: 346191360; Read time: 27388; Write time: 742 23:48:31 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 29920768; Write bytes: 298268160; Read time: 186; Write time: 1102 23:48:31 INFO - Running post-run listener: _upload_blobber_files 23:48:31 INFO - Blob upload gear active. 23:48:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:48:31 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 23:48:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:48:32 INFO - (blobuploader) - INFO - Open directory for files ... 23:48:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:48:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:48:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:48:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:48:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:48:34 INFO - (blobuploader) - INFO - Done attempting. 23:48:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:48:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:48:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:48:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:48:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:48:35 INFO - (blobuploader) - INFO - Done attempting. 23:48:35 INFO - (blobuploader) - INFO - Iteration through files over. 23:48:35 INFO - Return code: 0 23:48:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:48:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:48:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b1e60b26aec4955f41bd3aab7a741486a7f9192be690920fc62f48f9fb06faabd90cf5b6373f64c1ec5bca0981605aed2c42d6fadfaf2c3542556e9a9380c2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e2a7aa4908bd9090b8c195ed3402fb7a0f7634224576e7062c521c8a2c8dd5bfbabaf59fd43ad6cc5dbff59ce349a434b3be9d13eacc7c9ee75e1f60ed69aa9"} 23:48:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:48:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:48:35 INFO - Contents: 23:48:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b1e60b26aec4955f41bd3aab7a741486a7f9192be690920fc62f48f9fb06faabd90cf5b6373f64c1ec5bca0981605aed2c42d6fadfaf2c3542556e9a9380c2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e2a7aa4908bd9090b8c195ed3402fb7a0f7634224576e7062c521c8a2c8dd5bfbabaf59fd43ad6cc5dbff59ce349a434b3be9d13eacc7c9ee75e1f60ed69aa9"} 23:48:35 INFO - Running post-run listener: copy_logs_to_upload_dir 23:48:35 INFO - Copying logs to upload dir... 23:48:35 INFO - mkdir: /builds/slave/test/build/upload/logs 23:48:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.834285 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2016-06-02 23:48:35.138619) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 23:48:35.142586) ========= 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.7lrtEBzJm0/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.jZHuMRxWNW/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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/Mozilla-Aurora/sha512/8b1e60b26aec4955f41bd3aab7a741486a7f9192be690920fc62f48f9fb06faabd90cf5b6373f64c1ec5bca0981605aed2c42d6fadfaf2c3542556e9a9380c2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e2a7aa4908bd9090b8c195ed3402fb7a0f7634224576e7062c521c8a2c8dd5bfbabaf59fd43ad6cc5dbff59ce349a434b3be9d13eacc7c9ee75e1f60ed69aa9"} build_url:https://queue.taskcluster.net/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011230 build_url: 'https://queue.taskcluster.net/v1/task/Edt8UKEeRP2qGgbOb65K4A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b1e60b26aec4955f41bd3aab7a741486a7f9192be690920fc62f48f9fb06faabd90cf5b6373f64c1ec5bca0981605aed2c42d6fadfaf2c3542556e9a9380c2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e2a7aa4908bd9090b8c195ed3402fb7a0f7634224576e7062c521c8a2c8dd5bfbabaf59fd43ad6cc5dbff59ce349a434b3be9d13eacc7c9ee75e1f60ed69aa9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 23:48:35.173312) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 23:48:35.173640) ========= 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.7lrtEBzJm0/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.jZHuMRxWNW/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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.005104 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 23:48:35.223509) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 23:48:35.224066) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 23:48:35.224667) ========= ========= Total master_lag: 2.59 =========