builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0001 starttime: 1461584777.89 results: success (0) buildid: 20160422035709 builduid: 3af33a9a7de54ba3986a82f34ab86caa revision: 80598be5d80e695727ff74e712faa623b02f3c70 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:17.888989) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:17.889368) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-04-25 04:46:17.889637) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-04-25 04:46:19.373823) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:19.374069) ========= 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.b0uNuoBdc8/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.yxorJ7gQmK/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-25 04:46:19-- 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% 163M=0s 2016-04-25 04:46:19 (163 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105655 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:19.540824) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 04:46:19.541098) ========= 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.b0uNuoBdc8/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.yxorJ7gQmK/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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.027109 ========= master_lag: 1.54 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 04:46:21.105920) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:21.106240) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 80598be5d80e695727ff74e712faa623b02f3c70 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 80598be5d80e695727ff74e712faa623b02f3c70 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b0uNuoBdc8/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.yxorJ7gQmK/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-25 04:46:21,214 truncating revision to first 12 chars 2016-04-25 04:46:21,214 Setting DEBUG logging. 2016-04-25 04:46:21,214 attempt 1/10 2016-04-25 04:46:21,214 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/80598be5d80e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 04:46:21,864 unpacking tar archive at: fx-team-80598be5d80e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.891669 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:22.049603) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:22.049907) ========= script_repo_revision: 80598be5d80e695727ff74e712faa623b02f3c70 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:22.050283) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:22.050557) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:22.064528) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-04-25 04:46:22.064855) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b0uNuoBdc8/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.yxorJ7gQmK/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 04:46:22 INFO - MultiFileLogger online at 20160425 04:46:22 in /builds/slave/test 04:46:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:46:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:46:22 INFO - {'append_to_log': False, 04:46:22 INFO - 'base_work_dir': '/builds/slave/test', 04:46:22 INFO - 'blob_upload_branch': 'Fx-Team', 04:46:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:46:22 INFO - 'branch': 'Fx-Team', 04:46:22 INFO - 'buildbot_json_path': 'buildprops.json', 04:46:22 INFO - 'config_files': ('talos/mac_config.py',), 04:46:22 INFO - 'default_actions': ('clobber', 04:46:22 INFO - 'read-buildbot-config', 04:46:22 INFO - 'download-and-extract', 04:46:22 INFO - 'populate-webroot', 04:46:22 INFO - 'create-virtualenv', 04:46:22 INFO - 'install', 04:46:22 INFO - 'run-tests'), 04:46:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:46:22 INFO - 'download_minidump_stackwalk': True, 04:46:22 INFO - 'download_symbols': 'ondemand', 04:46:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:46:22 INFO - 'tooltool.py': '/tools/tooltool.py', 04:46:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:46:22 INFO - '/tools/misc-python/virtualenv.py')}, 04:46:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:46:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:46:22 INFO - 'installer_path': 'installer.exe', 04:46:22 INFO - 'log_level': 'info', 04:46:22 INFO - 'log_name': 'talos', 04:46:22 INFO - 'log_to_console': True, 04:46:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:46:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:46:22 INFO - 'opt_config_files': (), 04:46:22 INFO - 'pip_index': False, 04:46:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:22 INFO - 'cmd': ('bash', 04:46:22 INFO - '-c', 04:46:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:22 INFO - 'enabled': True, 04:46:22 INFO - 'halt_on_failure': False, 04:46:22 INFO - 'name': 'check_screen_resolution'},), 04:46:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:22 INFO - 'cmd': ('bash', 04:46:22 INFO - '-c', 04:46:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:22 INFO - 'enabled': True, 04:46:22 INFO - 'halt_on_failure': False, 04:46:22 INFO - 'name': 'check_screen_resolution'},), 04:46:22 INFO - 'run_cmd_checks_enabled': True, 04:46:22 INFO - 'sps_profile': False, 04:46:22 INFO - 'sps_profile_interval': 0, 04:46:22 INFO - 'suite': 'dromaeojs', 04:46:22 INFO - 'system_bits': '32', 04:46:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:46:22 INFO - 'title': 't-yosemite-r7-0001', 04:46:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:46:22 INFO - 'use_talos_json': True, 04:46:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:46:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:46:22 INFO - 'work_dir': 'build'} 04:46:22 INFO - ##### 04:46:22 INFO - ##### Running clobber step. 04:46:22 INFO - ##### 04:46:22 INFO - Running pre-action listener: _resource_record_pre_action 04:46:22 INFO - Running main action method: clobber 04:46:22 INFO - rmtree: /builds/slave/test/build 04:46:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:46:23 INFO - Running post-action listener: _resource_record_post_action 04:46:23 INFO - ##### 04:46:23 INFO - ##### Running read-buildbot-config step. 04:46:23 INFO - ##### 04:46:23 INFO - Running pre-action listener: _resource_record_pre_action 04:46:23 INFO - Running main action method: read_buildbot_config 04:46:23 INFO - Using buildbot properties: 04:46:23 INFO - { 04:46:23 INFO - "project": "", 04:46:23 INFO - "product": "firefox", 04:46:23 INFO - "script_repo_revision": "production", 04:46:23 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:46:23 INFO - "repository": "", 04:46:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:46:23 INFO - "buildid": "20160422035709", 04:46:23 INFO - "pgo_build": "False", 04:46:23 INFO - "basedir": "/builds/slave/test", 04:46:23 INFO - "buildnumber": 767, 04:46:23 INFO - "slavename": "t-yosemite-r7-0001", 04:46:23 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:46:23 INFO - "platform": "yosemite_r7", 04:46:23 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:46:23 INFO - "branch": "fx-team", 04:46:23 INFO - "repo_path": "integration/fx-team", 04:46:23 INFO - "revision": "80598be5d80e695727ff74e712faa623b02f3c70", 04:46:23 INFO - "stage_platform": "macosx64", 04:46:23 INFO - "builduid": "3af33a9a7de54ba3986a82f34ab86caa", 04:46:23 INFO - "slavebuilddir": "test" 04:46:23 INFO - } 04:46:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 04:46:23 INFO - Running post-action listener: _resource_record_post_action 04:46:23 INFO - ##### 04:46:23 INFO - ##### Running download-and-extract step. 04:46:23 INFO - ##### 04:46:23 INFO - Running pre-action listener: _resource_record_pre_action 04:46:23 INFO - Running main action method: download_and_extract 04:46:23 INFO - mkdir: /builds/slave/test/build/tests 04:46:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:46:23 INFO - https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:46:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:46:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:46:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:46:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 04:46:26 INFO - Downloaded 1199 bytes. 04:46:26 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:46:26 INFO - Using the following test package requirements: 04:46:26 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:46:26 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:26 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 04:46:26 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:26 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 04:46:26 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:26 INFO - u'jsshell-mac.zip'], 04:46:26 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:26 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 04:46:26 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:46:26 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:26 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 04:46:26 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:26 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 04:46:26 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:26 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 04:46:26 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:46:26 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 04:46:26 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:46:26 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')]} 04:46:26 INFO - https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:46:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:46:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:46:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:46:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:46:32 INFO - Downloaded 18105796 bytes. 04:46:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:46:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:46:36 INFO - Return code: 0 04:46:36 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:46:36 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')]} 04:46:36 INFO - https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:46:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:46:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:46:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:46:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:46:38 INFO - Downloaded 18105796 bytes. 04:46:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:46:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:46:41 INFO - Return code: 0 04:46: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')]} 04:46:41 INFO - https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:46:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:46:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:46:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:46:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:46:43 INFO - Downloaded 10845952 bytes. 04:46:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:46:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:46:43 INFO - Return code: 0 04:46:43 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')]} 04:46:43 INFO - https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:46:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:46:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:46:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 04:46:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 04:46:46 INFO - Downloaded 110034607 bytes. 04:46:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:46:46 INFO - mkdir: /builds/slave/test/properties 04:46:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:46:46 INFO - Writing to file /builds/slave/test/properties/build_url 04:46:46 INFO - Contents: 04:46:46 INFO - build_url:https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:46:47 INFO - Running post-action listener: _resource_record_post_action 04:46:47 INFO - Running post-action listener: set_extra_try_arguments 04:46:47 INFO - ##### 04:46:47 INFO - ##### Running populate-webroot step. 04:46:47 INFO - ##### 04:46:47 INFO - Running pre-action listener: _resource_record_pre_action 04:46:47 INFO - Running main action method: populate_webroot 04:46:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:46:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:46:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:46:47 INFO - '--tppagecycles', 04:46:47 INFO - '7'], 04:46:47 INFO - 'tests': ['tsvgm']}}, 04:46:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:46:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:46:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:46:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:46:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:46:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:46:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:47 INFO - 'talos_options': ['--disable-e10s'], 04:46:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:46:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:46:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:47 INFO - 'talos_options': ['--disable-e10s'], 04:46:47 INFO - 'tests': ['damp', 'tps']}, 04:46:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:47 INFO - 'tests': ['damp', 'tps']}, 04:46:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:46:47 INFO - 'tests': ['dromaeo_dom']}, 04:46:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:46:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:46:47 INFO - 'tests': ['a11yr', 04:46:47 INFO - 'ts_paint', 04:46:47 INFO - 'tpaint', 04:46:47 INFO - 'sessionrestore', 04:46:47 INFO - 'sessionrestore_no_auto_restore', 04:46:47 INFO - 'tabpaint']}, 04:46:47 INFO - 'other-e10s': {'tests': ['a11yr', 04:46:47 INFO - 'ts_paint', 04:46:47 INFO - 'tpaint', 04:46:47 INFO - 'sessionrestore', 04:46:47 INFO - 'sessionrestore_no_auto_restore', 04:46:47 INFO - 'tabpaint']}, 04:46:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:46:47 INFO - 'ts_paint', 04:46:47 INFO - 'tpaint', 04:46:47 INFO - 'sessionrestore', 04:46:47 INFO - 'sessionrestore_no_auto_restore', 04:46:47 INFO - 'tabpaint']}, 04:46:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:46:47 INFO - 'ts_paint', 04:46:47 INFO - 'tpaint', 04:46:47 INFO - 'sessionrestore', 04:46:47 INFO - 'sessionrestore_no_auto_restore', 04:46:47 INFO - 'tabpaint']}, 04:46:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:46:47 INFO - 'tests': ['a11yr', 04:46:47 INFO - 'ts_paint', 04:46:47 INFO - 'tpaint', 04:46:47 INFO - 'sessionrestore', 04:46:47 INFO - 'sessionrestore_no_auto_restore', 04:46:47 INFO - 'tabpaint']}, 04:46:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:46:47 INFO - 'tests': ['a11yr', 04:46:47 INFO - 'ts_paint', 04:46:47 INFO - 'tpaint', 04:46:47 INFO - 'sessionrestore', 04:46:47 INFO - 'sessionrestore_no_auto_restore', 04:46:47 INFO - 'tabpaint']}, 04:46:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:46:47 INFO - 'tests': ['tsvgx', 04:46:47 INFO - 'tsvgr_opacity', 04:46:47 INFO - 'tart', 04:46:47 INFO - 'tscrollx', 04:46:47 INFO - 'cart']}, 04:46:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:46:47 INFO - 'tsvgr_opacity', 04:46:47 INFO - 'tart', 04:46:47 INFO - 'tscrollx', 04:46:47 INFO - 'cart']}, 04:46:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:47 INFO - 'talos_options': ['--disable-e10s'], 04:46:47 INFO - 'tests': ['tp5o']}, 04:46:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:47 INFO - 'tests': ['tp5o']}, 04:46:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:47 INFO - 'talos_options': ['--disable-e10s', 04:46:47 INFO - '--xperf_path', 04:46:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:46:47 INFO - 'tests': ['tp5n']}, 04:46:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:47 INFO - 'talos_options': ['--xperf_path', 04:46:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:46:47 INFO - 'tests': ['tp5n']}}, 04:46:47 INFO - 'talos.zip': {'path': '', 04:46:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:46:47 INFO - Running post-action listener: _resource_record_post_action 04:46:47 INFO - ##### 04:46:47 INFO - ##### Running create-virtualenv step. 04:46:47 INFO - ##### 04:46:47 INFO - Running pre-action listener: _resource_record_pre_action 04:46:47 INFO - Running main action method: create_virtualenv 04:46:47 INFO - Creating virtualenv /builds/slave/test/build/venv 04:46:47 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 04:46:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:46:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:46:48 INFO - Using real prefix '/tools/python27' 04:46:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:46:48 INFO - Installing distribute.............................................................................................................................................................................................done. 04:46:51 INFO - Installing pip.................done. 04:46:51 INFO - Return code: 0 04:46:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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 0x1009aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6b5a35e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084ae00>, '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 0x100673db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100845b20>, '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.b0uNuoBdc8/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.yxorJ7gQmK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 04:46: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 04:46: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 04:46:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b0uNuoBdc8/Render', 04:46:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:51 INFO - 'HOME': '/Users/cltbld', 04:46:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:51 INFO - 'LOGNAME': 'cltbld', 04:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:51 INFO - 'MOZ_NO_REMOTE': '1', 04:46:51 INFO - 'NO_EM_RESTART': '1', 04:46:51 INFO - 'PAGER': '/bin/cat', 04:46:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:51 INFO - 'PWD': '/builds/slave/test', 04:46:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:51 INFO - 'SHELL': '/bin/bash', 04:46:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxorJ7gQmK/Listeners', 04:46:51 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:46:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:51 INFO - 'USER': 'cltbld', 04:46:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:51 INFO - 'XPC_FLAGS': '0x0', 04:46:51 INFO - 'XPC_SERVICE_NAME': '0', 04:46:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:51 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 04:46:51 INFO - Cleaning up... 04:46:51 INFO - Return code: 0 04:46:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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', '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 0x1009aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6b5a35e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084ae00>, '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 0x100673db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100845b20>, '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.b0uNuoBdc8/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.yxorJ7gQmK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 04:46: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', 'psutil>=0.7.1'] in /builds/slave/test/build 04:46: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 psutil>=0.7.1 04:46:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b0uNuoBdc8/Render', 04:46:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:51 INFO - 'HOME': '/Users/cltbld', 04:46:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:51 INFO - 'LOGNAME': 'cltbld', 04:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:51 INFO - 'MOZ_NO_REMOTE': '1', 04:46:51 INFO - 'NO_EM_RESTART': '1', 04:46:51 INFO - 'PAGER': '/bin/cat', 04:46:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:51 INFO - 'PWD': '/builds/slave/test', 04:46:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:51 INFO - 'SHELL': '/bin/bash', 04:46:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxorJ7gQmK/Listeners', 04:46:51 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:46:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:51 INFO - 'USER': 'cltbld', 04:46:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:51 INFO - 'XPC_FLAGS': '0x0', 04:46:51 INFO - 'XPC_SERVICE_NAME': '0', 04:46:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:52 INFO - Downloading/unpacking psutil>=0.7.1 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:46:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:46:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:46:55 INFO - Installing collected packages: psutil 04:46:55 INFO - Running setup.py install for psutil 04:46:55 INFO - building 'psutil._psutil_osx' extension 04:46: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 04:46: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:46: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:46:55 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 04:46:56 INFO - building 'psutil._psutil_posix' extension 04:46: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 04:46:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:46:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:46:56 INFO - ^ 04:46:56 INFO - 1 warning generated. 04:46: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 04:46:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:46:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:46:56 INFO - Successfully installed psutil 04:46:56 INFO - Cleaning up... 04:46:56 INFO - Return code: 0 04:46:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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 0x1009aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6b5a35e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084ae00>, '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 0x100673db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100845b20>, '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.b0uNuoBdc8/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.yxorJ7gQmK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 04:46: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 04:46: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 04:46:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b0uNuoBdc8/Render', 04:46:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:56 INFO - 'HOME': '/Users/cltbld', 04:46:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:56 INFO - 'LOGNAME': 'cltbld', 04:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:56 INFO - 'MOZ_NO_REMOTE': '1', 04:46:56 INFO - 'NO_EM_RESTART': '1', 04:46:56 INFO - 'PAGER': '/bin/cat', 04:46:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:56 INFO - 'PWD': '/builds/slave/test', 04:46:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:56 INFO - 'SHELL': '/bin/bash', 04:46:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxorJ7gQmK/Listeners', 04:46:56 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:46:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:56 INFO - 'USER': 'cltbld', 04:46:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:56 INFO - 'XPC_FLAGS': '0x0', 04:46:56 INFO - 'XPC_SERVICE_NAME': '0', 04:46:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:46:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:46: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) 04:46:59 INFO - Installing collected packages: mozsystemmonitor 04:46:59 INFO - Running setup.py install for mozsystemmonitor 04:46:59 INFO - Successfully installed mozsystemmonitor 04:46:59 INFO - Cleaning up... 04:46:59 INFO - Return code: 0 04:46:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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 0x1009aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6b5a35e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084ae00>, '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 0x100673db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100845b20>, '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.b0uNuoBdc8/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.yxorJ7gQmK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 04:46: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 04:46: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 04:46:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b0uNuoBdc8/Render', 04:46:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:59 INFO - 'HOME': '/Users/cltbld', 04:46:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:59 INFO - 'LOGNAME': 'cltbld', 04:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:59 INFO - 'MOZ_NO_REMOTE': '1', 04:46:59 INFO - 'NO_EM_RESTART': '1', 04:46:59 INFO - 'PAGER': '/bin/cat', 04:46:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:59 INFO - 'PWD': '/builds/slave/test', 04:46:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:59 INFO - 'SHELL': '/bin/bash', 04:46:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxorJ7gQmK/Listeners', 04:46:59 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:46:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:59 INFO - 'USER': 'cltbld', 04:46:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:59 INFO - 'XPC_FLAGS': '0x0', 04:46:59 INFO - 'XPC_SERVICE_NAME': '0', 04:46:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:59 INFO - Downloading/unpacking blobuploader==1.2.4 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:47:01 INFO - Downloading blobuploader-1.2.4.tar.gz 04:47:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:47:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:47:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:03 INFO - Downloading docopt-0.6.1.tar.gz 04:47:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:47:03 INFO - Installing collected packages: blobuploader, requests, docopt 04:47:03 INFO - Running setup.py install for blobuploader 04:47:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:47:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:47:03 INFO - Running setup.py install for requests 04:47:03 INFO - Running setup.py install for docopt 04:47:04 INFO - Successfully installed blobuploader requests docopt 04:47:04 INFO - Cleaning up... 04:47:04 INFO - Return code: 0 04:47:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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 0x1009aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6b5a35e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084ae00>, '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 0x100673db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100845b20>, '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.b0uNuoBdc8/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.yxorJ7gQmK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 04:47: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 04:47: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 04:47:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b0uNuoBdc8/Render', 04:47:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:04 INFO - 'HOME': '/Users/cltbld', 04:47:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:04 INFO - 'LOGNAME': 'cltbld', 04:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:04 INFO - 'MOZ_NO_REMOTE': '1', 04:47:04 INFO - 'NO_EM_RESTART': '1', 04:47:04 INFO - 'PAGER': '/bin/cat', 04:47:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:04 INFO - 'PWD': '/builds/slave/test', 04:47:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:04 INFO - 'SHELL': '/bin/bash', 04:47:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxorJ7gQmK/Listeners', 04:47:04 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:47:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:04 INFO - 'USER': 'cltbld', 04:47:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:04 INFO - 'XPC_FLAGS': '0x0', 04:47:04 INFO - 'XPC_SERVICE_NAME': '0', 04:47:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-pj8LLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-8Bii2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-6x7rQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-YkUYoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-13xH67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:04 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-pYk_rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:05 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-hkpojl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:05 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-gqIAdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:05 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-Poq9zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:05 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-erGN6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:05 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-UdNnuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:05 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-cFvjNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:05 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-E6uTZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:05 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-YP41OB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:05 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-v166tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:05 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-haa459-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:06 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-Ufa2hP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:47:06 INFO - Running setup.py install for manifestparser 04:47:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:47:06 INFO - Running setup.py install for mozcrash 04:47:06 INFO - Running setup.py install for mozdebug 04:47:06 INFO - Running setup.py install for mozdevice 04:47:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:47:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:47:06 INFO - Running setup.py install for mozfile 04:47:06 INFO - Running setup.py install for mozhttpd 04:47:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:47:07 INFO - Running setup.py install for mozinfo 04:47:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:47:07 INFO - Running setup.py install for mozInstall 04:47:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:47:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:47:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:47:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:47:07 INFO - Running setup.py install for mozleak 04:47:07 INFO - Running setup.py install for mozlog 04:47:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:47:07 INFO - Running setup.py install for moznetwork 04:47:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:47:07 INFO - Running setup.py install for mozprocess 04:47:07 INFO - Running setup.py install for mozprofile 04:47:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:47:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:47:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:47:08 INFO - Running setup.py install for mozrunner 04:47:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:47:08 INFO - Running setup.py install for mozscreenshot 04:47:08 INFO - Running setup.py install for moztest 04:47:08 INFO - Running setup.py install for mozversion 04:47:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:47:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:47:08 INFO - Cleaning up... 04:47:08 INFO - Return code: 0 04:47:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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 0x1009aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6b5a35e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084ae00>, '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 0x100673db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100845b20>, '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.b0uNuoBdc8/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.yxorJ7gQmK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 04:47: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 04:47: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 04:47:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b0uNuoBdc8/Render', 04:47:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:09 INFO - 'HOME': '/Users/cltbld', 04:47:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:09 INFO - 'LOGNAME': 'cltbld', 04:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:09 INFO - 'MOZ_NO_REMOTE': '1', 04:47:09 INFO - 'NO_EM_RESTART': '1', 04:47:09 INFO - 'PAGER': '/bin/cat', 04:47:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:09 INFO - 'PWD': '/builds/slave/test', 04:47:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:09 INFO - 'SHELL': '/bin/bash', 04:47:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxorJ7gQmK/Listeners', 04:47:09 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:47:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:09 INFO - 'USER': 'cltbld', 04:47:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:09 INFO - 'XPC_FLAGS': '0x0', 04:47:09 INFO - 'XPC_SERVICE_NAME': '0', 04:47:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-uEiy6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47: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)) 04:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-xwtFnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47: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)) 04:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-CNoDfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47: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)) 04:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-s9GDBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47: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)) 04:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-XI9Hkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47: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)) 04:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-GiPmnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:09 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)) 04:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:09 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-pFfvCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47: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)) 04:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-Vw0KUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47: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)) 04:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-9vvE53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47: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)) 04:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-OTpv4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47: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)) 04:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-JU78lQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47: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)) 04:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-3UILaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47: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)) 04:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-k7qDrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47: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)) 04:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-kTbv6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47: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)) 04:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-ivMT1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:10 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)) 04:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:10 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-Eso9tC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47: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)) 04:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:11 INFO - Running setup.py (path:/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/pip-flInGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:13 INFO - Downloading blessings-1.6.tar.gz 04:47:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:47:14 INFO - Installing collected packages: blessings 04:47:14 INFO - Running setup.py install for blessings 04:47:14 INFO - Successfully installed blessings 04:47:14 INFO - Cleaning up... 04:47:14 INFO - Return code: 0 04:47:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:47:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:14 INFO - Reading from file tmpfile_stdout 04:47:14 INFO - Current package versions: 04:47:14 INFO - blessings == 1.6 04:47:14 INFO - blobuploader == 1.2.4 04:47:14 INFO - docopt == 0.6.1 04:47:14 INFO - manifestparser == 1.1 04:47:14 INFO - mozInstall == 1.12 04:47:14 INFO - mozcrash == 0.17 04:47:14 INFO - mozdebug == 0.1 04:47:14 INFO - mozdevice == 0.48 04:47:14 INFO - mozfile == 1.2 04:47:14 INFO - mozhttpd == 0.7 04:47:14 INFO - mozinfo == 0.9 04:47:14 INFO - mozleak == 0.1 04:47:14 INFO - mozlog == 3.1 04:47:14 INFO - moznetwork == 0.27 04:47:14 INFO - mozprocess == 0.22 04:47:14 INFO - mozprofile == 0.28 04:47:14 INFO - mozrunner == 6.11 04:47:14 INFO - mozscreenshot == 0.1 04:47:14 INFO - mozsystemmonitor == 0.0 04:47:14 INFO - moztest == 0.7 04:47:14 INFO - mozversion == 1.4 04:47:14 INFO - psutil == 3.1.1 04:47:14 INFO - requests == 1.2.3 04:47:14 INFO - wsgiref == 0.1.2 04:47:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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 0x1009aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6b5a35e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084ae00>, '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 0x100673db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100845b20>, '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.b0uNuoBdc8/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.yxorJ7gQmK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 04:47: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 04:47: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 04:47:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b0uNuoBdc8/Render', 04:47:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:14 INFO - 'HOME': '/Users/cltbld', 04:47:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:14 INFO - 'LOGNAME': 'cltbld', 04:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:14 INFO - 'MOZ_NO_REMOTE': '1', 04:47:14 INFO - 'NO_EM_RESTART': '1', 04:47:14 INFO - 'PAGER': '/bin/cat', 04:47:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:14 INFO - 'PWD': '/builds/slave/test', 04:47:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:14 INFO - 'SHELL': '/bin/bash', 04:47:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxorJ7gQmK/Listeners', 04:47:14 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:47:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:14 INFO - 'USER': 'cltbld', 04:47:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:14 INFO - 'XPC_FLAGS': '0x0', 04:47:14 INFO - 'XPC_SERVICE_NAME': '0', 04:47:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47: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)) 04:47:14 INFO - Cleaning up... 04:47:14 INFO - Return code: 0 04:47:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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 0x1009aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6b5a35e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084ae00>, '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 0x100673db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100845b20>, '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.b0uNuoBdc8/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.yxorJ7gQmK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 04:47: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 04:47: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 04:47:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b0uNuoBdc8/Render', 04:47:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:14 INFO - 'HOME': '/Users/cltbld', 04:47:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:14 INFO - 'LOGNAME': 'cltbld', 04:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:14 INFO - 'MOZ_NO_REMOTE': '1', 04:47:14 INFO - 'NO_EM_RESTART': '1', 04:47:14 INFO - 'PAGER': '/bin/cat', 04:47:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:14 INFO - 'PWD': '/builds/slave/test', 04:47:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:14 INFO - 'SHELL': '/bin/bash', 04:47:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxorJ7gQmK/Listeners', 04:47:14 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:47:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:14 INFO - 'USER': 'cltbld', 04:47:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:14 INFO - 'XPC_FLAGS': '0x0', 04:47:14 INFO - 'XPC_SERVICE_NAME': '0', 04:47:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:15 INFO - Downloading/unpacking jsonschema 04:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:47:17 INFO - Downloading/unpacking functools32 (from jsonschema) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:18 INFO - Downloading functools32-3.2.3-2.tar.gz 04:47:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:47:18 INFO - warning: no files found matching '*.txt' 04:47:18 INFO - no previously-included directories found matching 'build' 04:47:18 INFO - no previously-included directories found matching 'dist' 04:47:18 INFO - no previously-included directories found matching '.git*' 04:47:18 INFO - Installing collected packages: jsonschema, functools32 04:47:18 INFO - Running setup.py install for functools32 04:47:18 INFO - warning: no files found matching '*.txt' 04:47:18 INFO - no previously-included directories found matching 'build' 04:47:18 INFO - no previously-included directories found matching 'dist' 04:47:18 INFO - no previously-included directories found matching '.git*' 04:47:18 INFO - Successfully installed jsonschema functools32 04:47:18 INFO - Cleaning up... 04:47:18 INFO - Return code: 0 04:47:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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 0x1009aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6b5a35e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084ae00>, '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 0x100673db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100845b20>, '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.b0uNuoBdc8/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.yxorJ7gQmK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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 04:47: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 04:47: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 04:47:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b0uNuoBdc8/Render', 04:47:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:18 INFO - 'HOME': '/Users/cltbld', 04:47:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:18 INFO - 'LOGNAME': 'cltbld', 04:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:18 INFO - 'MOZ_NO_REMOTE': '1', 04:47:18 INFO - 'NO_EM_RESTART': '1', 04:47:18 INFO - 'PAGER': '/bin/cat', 04:47:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:18 INFO - 'PWD': '/builds/slave/test', 04:47:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:18 INFO - 'SHELL': '/bin/bash', 04:47:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxorJ7gQmK/Listeners', 04:47:18 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:47:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:18 INFO - 'USER': 'cltbld', 04:47:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:18 INFO - 'XPC_FLAGS': '0x0', 04:47:18 INFO - 'XPC_SERVICE_NAME': '0', 04:47:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:18 INFO - Downloading/unpacking flake8 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:47:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:47:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:47:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:47:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:47:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:47:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:47:22 INFO - no previously-included directories found matching 'docs/_build' 04:47:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:47:22 INFO - Running setup.py install for pep8 04:47:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:47:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:47:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:47:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:47:22 INFO - no previously-included directories found matching 'docs/_build' 04:47:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:47:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:47:22 INFO - Cleaning up... 04:47:22 INFO - Return code: 0 04:47:22 INFO - Running post-action listener: _resource_record_post_action 04:47:22 INFO - Running post-action listener: _start_resource_monitoring 04:47:22 INFO - Starting resource monitoring. 04:47:22 INFO - ##### 04:47:22 INFO - ##### Running install step. 04:47:22 INFO - ##### 04:47:22 INFO - Running pre-action listener: _resource_record_pre_action 04:47:22 INFO - Running main action method: install 04:47:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:23 INFO - Reading from file tmpfile_stdout 04:47:23 INFO - Detecting whether we're running mozinstall >=1.0... 04:47:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:47:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:47:23 INFO - Reading from file tmpfile_stdout 04:47:23 INFO - Output received: 04:47:23 INFO - Usage: mozinstall [options] installer 04:47:23 INFO - Options: 04:47:23 INFO - -h, --help show this help message and exit 04:47:23 INFO - -d DEST, --destination=DEST 04:47:23 INFO - Directory to install application into. [default: 04:47:23 INFO - "/builds/slave/test"] 04:47:23 INFO - --app=APP Application being installed. [default: firefox] 04:47:23 INFO - mkdir: /builds/slave/test/build/application 04:47:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:47:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:47:56 INFO - Reading from file tmpfile_stdout 04:47:56 INFO - Output received: 04:47:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:47:56 INFO - Running post-action listener: _resource_record_post_action 04:47:56 INFO - ##### 04:47:56 INFO - ##### Running run-tests step. 04:47:56 INFO - ##### 04:47:56 INFO - Running pre-action listener: _resource_record_pre_action 04:47:56 INFO - Running main action method: run_tests 04:47:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:47:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:47:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:47:56 INFO - 2016-04-25 04:47:56.789 screenresolution[1641:7526] starting screenresolution argv=screenresolution get 04:47:56 INFO - 2016-04-25 04:47:56.792 screenresolution[1641:7526] Display 0: 1600x1200x32@60 04:47:56 INFO - 2016-04-25 04:47:56.798 screenresolution[1642:7528] starting screenresolution argv=screenresolution list 04:47:56 INFO - Available Modes on Display 0 04:47:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:47:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:47:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:47:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:47:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:47:57 INFO - Intel Iris: 04:47:57 INFO - Chipset Model: Intel Iris 04:47:57 INFO - Type: GPU 04:47:57 INFO - Bus: Built-In 04:47:57 INFO - VRAM (Dynamic, Max): 1536 MB 04:47:57 INFO - Vendor: Intel (0x8086) 04:47:57 INFO - Device ID: 0x0a2e 04:47:57 INFO - Revision ID: 0x0009 04:47:57 INFO - Displays: 04:47:57 INFO - Display: 04:47:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:47:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:47:57 INFO - Display Serial Number: 1600x1200 60 04:47:57 INFO - Main Display: Yes 04:47:57 INFO - Mirror: Off 04:47:57 INFO - Online: Yes 04:47:57 INFO - Rotation: Supported 04:47:57 INFO - Return code: 0 04:47:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:47:57 INFO - Python 2.7.3 04:47:57 INFO - Return code: 0 04:47:57 INFO - grabbing minidump binary from tooltool 04:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:57 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 0x10084ae00>, '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 0x100673db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100845b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:47:57 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 04:47:57 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 04:47:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:47:57 INFO - Return code: 0 04:47:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:47:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:47:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:47:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:47:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:47:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:47:58 INFO - Return code: 0 04:47:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0001', '--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 04:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0001 --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 04:47:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b0uNuoBdc8/Render', 04:47:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:58 INFO - 'HOME': '/Users/cltbld', 04:47:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:58 INFO - 'LOGNAME': 'cltbld', 04:47:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:47:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:58 INFO - 'MOZ_NO_REMOTE': '1', 04:47:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:47:58 INFO - 'NO_EM_RESTART': '1', 04:47:58 INFO - 'PAGER': '/bin/cat', 04:47:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:58 INFO - 'PWD': '/builds/slave/test', 04:47:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:47:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:58 INFO - 'SHELL': '/bin/bash', 04:47:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxorJ7gQmK/Listeners', 04:47:58 INFO - 'TMPDIR': '/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/', 04:47:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:58 INFO - 'USER': 'cltbld', 04:47:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:58 INFO - 'XPC_FLAGS': '0x0', 04:47:58 INFO - 'XPC_SERVICE_NAME': '0', 04:47:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0001', '--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 04:47:58 INFO - mozversion application_buildid: 20160422035709 04:47:58 INFO - mozversion application_changeset: 80598be5d80e695727ff74e712faa623b02f3c70 04:47:58 INFO - mozversion application_display_name: Nightly 04:47:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:47:58 INFO - mozversion application_name: Firefox 04:47:58 INFO - mozversion application_remotingname: firefox 04:47:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:47:58 INFO - mozversion application_vendor: Mozilla 04:47:58 INFO - mozversion application_version: 48.0a1 04:47:58 INFO - mozversion platform_buildid: 20160422035709 04:47:58 INFO - mozversion platform_changeset: 80598be5d80e695727ff74e712faa623b02f3c70 04:47:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:47:58 INFO - mozversion platform_version: 48.0a1 04:47:58 INFO - using testdate: 1461584878 04:47:58 INFO - actual date: 1461584878 04:47:58 INFO - starting webserver on 'localhost:49270' 04:47:58 INFO - SUITE-START | Running 2 tests 04:47:58 INFO - TEST-START | dromaeo_css 04:47:58 INFO - operating with platform_type : mac_ 04:47:58 INFO - Initialising browser for dromaeo_css test... 04:47:58 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpeiEWBE/profile http://localhost:49270/getInfo.html) 04:48:00 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 04:48:00 INFO - PROCESS | 1660 | colorDepth:24 04:48:00 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 04:48:00 INFO - PROCESS | 1660 | __metrics 04:48:01 INFO - PROCESS | 1660 | 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" 04:48:01 ERROR - PROCESS | 1660 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:48:01 INFO - TEST-INFO | 1660: exit 0 04:48:01 INFO - Browser initialized. 04:48:01 INFO - Running cycle 1/1 for dromaeo_css test... 04:48:01 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpeiEWBE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:13 INFO - PROCESS | 1662 | RSS: Main: 238575616 04:48:13 INFO - PROCESS | 1662 | 04:49:05 INFO - PROCESS | 1662 | 1461584945541 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] 04:51:16 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 04:51:17 INFO - PROCESS | 1662 | RSS: Main: 372711424 04:51:17 INFO - PROCESS | 1662 | 04:54:20 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 04:54:21 INFO - PROCESS | 1662 | RSS: Main: 382775296 04:54:21 INFO - PROCESS | 1662 | 04:57:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 04:57:24 INFO - PROCESS | 1662 | RSS: Main: 379940864 04:57:24 INFO - PROCESS | 1662 | 05:00:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 05:00:26 INFO - PROCESS | 1662 | RSS: Main: 377544704 05:00:26 INFO - PROCESS | 1662 | 05:03:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 05:03:30 INFO - PROCESS | 1662 | RSS: Main: 345378816 05:03:30 INFO - PROCESS | 1662 | 05:06:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 05:06:33 INFO - PROCESS | 1662 | RSS: Main: 381874176 05:06:33 INFO - PROCESS | 1662 | 05:06:33 INFO - PROCESS | 1662 | __start_tp_report 05:06:33 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 05:06:33 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 05:06:33 INFO - PROCESS | 1662 | |i|pagename|runs| 05:06:33 INFO - PROCESS | 1662 | |0;dojo.html;3305.438075527161;3427.5715535558056;3449.689527942488;3451.1889705919107;3480.6867381935635;5567.053600887849;5657.377688454277;5677.176809362645;5689.089745640698;5689.402612725661;5616.803411880585;5674.09214525676;5676;5676;5691.430856914317;5112.539871411572;5116.948830511703;5143.665661731994;5159.355080614923;5216.478352164791;5203.19350500673;5206.479352064782;5213.426523082458;5214.895702085956;5223.555997740177;5127.9743601282125;5156.767945442464;5182.196759502283;5195.480451954793;5243.606729495284;5182.870428239286;5213.2701421800975;5218.504242096995;5234.581233501311;5260.579153667697;4088.325426304656;5110.233464980245;5148.9702059588235;5164.018836421105;5167.431582525919;5554.805581804658;5561.58288128392;5575.69333686647;5664.546836253131;5687.232223649796;5148.819791307286;6206.751729930843;6217.285012223561;6223.25320961483;6226.190595222637;6161.137440758297;6205.379462053804;6254.655993920333;6264.749410023594;6276.121343011982;5784.797532086357;5792.9710351447975;5814.651120932757;5852.092925596497;5852.765889364463;6251.249850017936;6276.152719382825;6277;6292.811215663537;6300.905486417708;6092.664903430265;6126.9387306127255;6173.475254603409;6181.474574661204;6191.473724733358;6226.097215903668;6254.6872656366995;6260.186175797117;6263.217097862767;6269.68651567429;6206.658633775095;6206.782762603287;6219.564630475823;6220.688965551704;6238.844028899223;6120.234970628671;6165.32181460039;6182.165407669997;6207.6275423474735;6246.75012999484;4852.296417019513;4854.199057155601;4855.660103792771;4856.3201151838775;4859.611231101503;4328.220920234388;4394.2969124939855;4406.074724307867;4417.867463976086;4422.7346359218545;5650.660960342392;5740.799072032459;5759.308882933991;5759.942400576025;5760.6255593386295;6153.046277826944;6233.906491402633;6260.843478912972;6264.248290205113;6275.748970041147;4907.312976183337;5662.943370566324;5684.801031963944;5693.914591281134;5695.430456954271;5326;5372.086745253317;5388.5419739321815;5402.8919421611345;5438.8912221755345;6264.968675156595;6283.403076707706;6308.621482711052;6313.43179113882;6332.398422149889;6255.374462553708;6263.530235232284;6266.498680105489;6274;6284.622922624657;6193.628382297076;6259.96870015647;6276.246850377984;6289.748410063729;6294.402031806971;6104.641056839945;6111.113888486234;6151.415615516519;6196.845078873064;6200.131981522501;5571.192177134212;6240.687965601611;6241.594296370721;6244.562880598314;6298.748050077946;4658.93011604833;6071.544634152545;6088.939110608837;6115.174451448997;6124.020156775073;5943.435373639497;6044.15381846533;6101.368489499298;6110.680268050674;6135.417135372132;5944.910826337695;5954.880902381841;5957.940420595739;5965.04559270532;5996.700164991646;5984.31180414258;6059;6114.266288045463;6123.785667501616;6131.4481696645735;5008.574271186844;5863.20846685692;5960.552958528215;6007.609505382136;6067.878642427215 05:06:33 INFO - PROCESS | 1662 | |1;ext.html;518.914379127444;527.7545941137371;562.0725802425999;580.4717706886734;615.6152404747033;30550.08349749508;31117.688823111763;31175.220619484524;31178.68821311789;38709.80645096778;42398;43251;43286.134277314435;43289;43294.35058474125;9581.329306948517;9847.55685994132;9854.458004809732;9862.161716254128;9880.752981175492;8576.05663377028;8851.95574022129;9584.137427631511;9722.951385243065;11531.711707207303;11543.711407214803;11652.475638596283;11678.708032299175;11680.065594752441;11681.240719353258;11466.827997580043;11564.653060408202;11569.479373428174;11581.710457238552;11621.012213961823;8576.185262400062;11503.769924601545;11800.46897889597;11816.763664726743;11833.349165795878;38609.613903861165;39188;39215.8039209805;39229.21541569153;39339.40990885139;3186.9840650796828;3267.9999999999764;3357.3788849062757;3429.2798512312443;3539.486774417721;23073.884630576908;27534.862325688242;27689.723102768916;28368.574471382944;29188;2685.7314268572986;2743.245607457965;2744.0395861448533;2819.2669905824428;2848.943021139586;491507;493326.53336733446;494224;494556.5272173652;495115;29772.40455190884;64969.675151624884;66221;66297;66333.66833165899;35806;35978.64021359805;36031;36060.81969590135;36098.278034439165;12843.743125137446;14848.480303189337;15010.399584016517;15182.240887955559;15184.392624295126;11788.23376480526;12881.871181288254;12935.094543382062;12972;12977.286249256382;7688.730894418669;7774.494657847222;7785.766427007199;7805.336546393507;8065.39509536787;3330.733541316689;3358.6473420290517;3369.4777309517062;3395.728341732705;3402.5406570113214;875.299760191844;998.9350692204983;1028.5268776362807;1076.1659713721772;1080.4273734920505;28742.99399521007;28816.855915720287;29137.56293655597;29454.263643409085;29756.2560935974;1080.308602494404;1081.6376513867942;1155.7688462307572;1176.758764453285;1222.914395992272;14294;15028.526647332885;16134.435294764628;16454.259558319904;16731.996080235225;646188;647041;657143;657620;659792.7010365013;54867;61842.38157618456;62907.6854615724;63755.043674344925;63869.40326491782;1458.9781153282709;1465.9560213193622;1466.0543949152736;1478.6672998575234;1482.3107255126201;28641;28865;29089.12732618025;29527.557086643286;29682.257943551584;54343;55984;55990;56041.719791400785;56094;585074;609162;609556;610030;610487;27140.050098246465;27446.17661470159;27533.724662753917;27562.173134805988;27579;45678.54321456743;45956;46376.30435543537;46854.29718554293;47958.28062578994;37858;38360.2327953445;38632.80683596564;39397.80301098476;39526;36971;37139.6286037147;37198.25603487969;37886.052848679006;38273.04317392087;37135;37558;37802;37846.62153378431;38348.80825595854;31401.8429907849;32518.34963300768;32835.68657253683;33449.66550334466;33533.49699754457;23884;24677.259682209562;24733.87633061823;25220.495590088463;25378 05:06:33 INFO - PROCESS | 1662 | |2;jquery.html;395.81990194461525;396.71832998571017;402.7080366734118;406.0498433665223;409.4615580511627;15929.920350398246;19884;20627.17491300348;20674.37976860695;21043.789562104375;19848.305309314175;20230.89884550575;20615.587688246225;20629.690554641693;20676.37970860873;5436.728163591824;5557.665780372233;5885.264341957255;5885.7939972100985;5892.322382925976;5582.748776305056;5638.577106716992;5791.623544469596;5796.681182534959;5801.883962320751;5744.6840423776675;5785.855353616151;5799.449052340021;5805.042168042268;5809.331926828427;5644.520215781664;5695.943040569624;5784.4504772046585;5801.6519008859605;5810.157527158581;5096.745162741885;5139.383274007106;5140.537351638371;5141.331626888518;5141.820036298712;13867.791983120262;23552.704601246925;23722.93246803898;24587;25194.622080668803;1873.7657792775904;2049.036952632275;2123.9804893651135;2219.5782801267706;2446.911124549576;9289.396189247676;10127.493625318704;13564.660883477993;14715.705685886222;14891.404343826234;1720.4924547258588;1783.0460703523552;1790.3196785221533;1792.9193186306647;1811.0582497101566;79333.38939537066;81176.18823811805;106162;128533.35733321274;128907;11094;11612.245204061708;11724.413779311;11725.472353744102;11771.64567086586;14948;14981.850181498046;15007.624809379635;15013.624659383384;15038.473653422077;8468.661253549912;8503.702370417006;8528.275096616857;8550.957245213734;8668.8699669505;8409.075001749803;8411.537365444838;8450.957745211235;8510.234078932926;8548.102449242864;3761.755485893408;4538.228501154812;4538.432695913011;4556.997460558672;4608.677392582487;1623.959401014984;1703.769991051217;1887.3488646416963;1935.5064458563259;1941.8155275248828;861.7156338408311;925.0102390442297;958.3387462650749;968.651285537206;995.7012896131132;19204.519887002938;19676.3113291037;20051.899740501496;20065.996700164935;20098.296559620343;918.9540522973824;937.3536941298355;937.7468083617385;942.9811403771886;973.4889183178747;8346.791330216673;10741.248112632196;10745.194110441591;10748.94625526883;10753.623623173151;584817;595234;603543;614242;614675;135105.324473377;137087;137549.31225343808;138010.3099484496;145809.27095364456;1017.0338178730236;1027.1679939249082;1028.8199565076081;1029.0224286927448;1054.8997845204694;17853.73219401684;18151;18209.99845008537;18433.90783046076;18476;52983.73508132435;53342.73328633332;53976.730116349165;54204.72897635487;55523.44476555183;73798.63100684462;76247;76335;78523;78635.60682196553;6603.80188594343;7162.355388014896;7900.249476299742;8056.194380561897;8068.47554908929;9089.77275568116;11711.180217385043;12215.572454964034;12532.624021279376;12924.159929604546;9257.67598134062;10914.345139291669;11296.548138074386;11306.830397544209;11401.030912372542;10756.51595678212;10857.34855908648;11056.281341712764;11152.776944461228;11267.492962816857;10879.456027198767;11023.61417350421;11077.224594278323;11388.658340249805;11565.653030409101;6549.312322206099;8014.438989270695;8171.346292296721;8243.093259741543;8453.196946290092;8375.246227839523;10067.597296108073;10238.590456381959;10508.947455262674;11306.717332066435 05:06:33 INFO - PROCESS | 1662 | |3;mootools.html;1409.619402761255;1587.9920600396997;1596.2178532519063;1858.8512918966476;1978.5350442645984;7983.241592048754;8807.647694092237;9050.275977921767;9070.274378049748;9078.636854525812;8669.69656062038;8890.199882010618;8903.065178156297;8909.73270801877;8911.732648020554;2163.8485306028583;2391.9162829300976;2445.608702607583;2477.838940468873;2489.726130125685;2452.227548322273;2478.7521247875247;2481.6153496208026;2482.9943872731574;2484.7018357797087;2401.603735383668;2461.716902556211;2462.322861213163;2463.6797216361847;2465.593177125781;2158.363282831561;2176.3351036457125;2428.672129262545;2430.611102223636;2440.389902524369;2434.780869721716;2442.3771938155833;2467.9506409871883;2468.0498008266945;2473.777360037587;8739.213470787596;8795.164459376349;8797.988231353413;8798.164174403486;8806.779830504225;5179.766910489037;5390.97304513475;5400.432954539795;5407.594430417695;5515.834524964257;3579.9642003579893;3590.856365745367;3604.963950360489;3649.2048269799375;3659.652333028358;1008.8839783424853;1021.7139201023651;1023.5649848814224;1031.2626472072504;1032.2825636182856;11842.052635789116;11880.584179553674;11881.465334059989;11883.940580297043;11887.346195959219;4562.954370456319;4629.490756016836;4804.222437698004;4836.903261934812;4878.463369029404;10026.145163144842;10030;10032.44821534808;10041.297109202433;10061.094501494754;5408.02655522002;5409.26974858397;5411.5129638331955;5453.372862120907;5459.863503412367;4440.35614835847;4440.511543730187;4502.16709908668;4614.2617181250835;4920.581750551171;3907.316219661548;3990.541087774885;4062.614051665087;4071.185762847442;4093.672506199497;1143.874173840877;1296.1510210811932;1337.6990177210243;1343.0531475309883;1357.1924704800629;727.8762610356252;896.3546246702364;928.6888892221046;943.9008904065112;953.5422996961497;5851.239338885917;5914.733836977346;6061;6062.302555284527;6129.417705317987;461.61455184920965;472.98581042568776;476.2166236540871;485.1752021563356;485.36902027364283;3819.52255968004;3943.4873466449176;4024.3158663027357;4102.692298077656;4112.856050038295;10516.842247366296;10955.671329860117;10982.890171098346;11029.889701103048;11045.116390688725;10999.23005389615;11000.504977276041;11003.944980275048;11015.394153321644;11021.393823339793;7710.498817576839;8159.46963447374;8229.83540329202;8239.052508961546;8253.009638843258;4904.411470623548;5993.0710739835395;6079.787207447718;6151.292601350902;6187.133801267736;10034.598616055277;10142;10195.643152489627;10262.076413122855;10310.639127630497;9959.900400995897;10257.281990260908;10388.896111039096;10534.999175078356;10574.15406767472;6913.861722765617;7205.099362579677;7445.478816482794;7611.885821712568;7778;8459.746207613782;8466.449680770731;8549.444286121383;8553.95723021381;8559.887214662054;7358.705651773869;7465.925340746523;7586.203448637813;7588.165301816685;7612.048493938258;7262.891056634049;7335.4498412618195;7343.2289609590225;7454.329110380092;7489.588072656056;7026.156861176692;7135.928640713734;7175.461840362098;7176.96411517939;7180.425565954607;4792.856214313576;4934.481879402754;4976.850694479172;5081.415637201769;5153.098207813692;4927.97536012332;5009.298698182184;5046.823361182341;5097.847064588068;5266.78932842682 05:06:33 INFO - PROCESS | 1662 | |4;prototype.html;310.09297803923533;315.70797012763194;323.6084337951079;329.64727741316784;331.41670659639027;5335.653182543137;5805.390434004426;5857.970710146448;5871.972404829148;5914.970425147868;4777.52224777522;5563.304586926634;5566.165075238706;5768.3654797972185;5771.624844385112;3951.308521008812;4320.438343015404;4361.760103194323;4379.189849877783;4396.274614688573;3737.495438115847;4111.075008123178;4214.7471151731;4328.545502722216;4381.072328175608;3582.5880023797336;3846.2114966649237;4321.6291292791675;4321.675874309423;4337.414449049385;3202.647708752035;3937.645611894929;3942.250972315251;4156.459660244179;4272.807723652443;3732.8133593320226;3732.9813350933073;3739.158689294914;3744.326021316162;3750.4374343848367;4665.766711664403;5319.308489896327;5678.687672178028;5718.48533631971;5742;4189.832406703728;4357.128574285156;4925.162722337212;4997.550220480138;5023.6985780853265;6224.315325314212;6225.595336303126;6243.125962365227;6244.968775156095;6248.3751624837605;3638.6361363863402;3888.727789054767;4135.462389889295;4140.254754144253;4161.604647558477;5658.66048037115;5699.74351154199;6525.70634321452;6587.7694280700425;6589.967050164719;4211.136716973013;4214.789260536961;4217.325227963511;4225.640820530228;4229.577042295746;4013.2174226026227;4233.767142807175;4277.118068050048;4281.681696685094;4293.441852559147;4983.835264606928;4999.350084488993;5013.222950442687;5021.347224860818;5029.5221953914315;6249.218847644044;6278.434940855345;6280.717367718464;6303.7793677222;6316.242050953823;1323.0936808286356;1442.9927850360682;1448.8406275309708;1527.7020980908837;1536.64657128861;774.0440555913442;901;984.5766320482261;992.4987881119988;1023.4575674892319;4589.908201835945;4607.262837945913;5782.855428614318;5826.388229235954;5853.326867410302;5259.868503287448;5821.505172060338;6104.572679912452;6241.96879015602;6413.262474815363;5563.248961390242;5804.332501762351;5808.664007278349;5812.854678632983;5853.239078919713;3117.4544454720335;3354.4129777288877;3378.3412234614507;3385.8476368563474;3406.3357645259434;5876.444750925714;6414.550981431348;6681.532292739462;6695.15455931237;6703.162104736908;6493.448056915215;6551.705173267214;6574.638394888231;6587.53887227899;6615.536912416181;6586.694656870057;6666.566673166229;6703.162104736908;6706.329367063352;6707.496937729592;6148.907766383597;6536;6554.934450655433;6576.638284894186;6588.934110658832;6352.618842869442;6433.710483028181;6446.774362897464;6476.255230648536;6492.740290388331;5064.5441910228255;6401.743930242738;6648.434883034996;6804.387605115563;6831.385175334244;3927.941080883732;4004.579519150447;4096.795160242035;4124.340105583093;4198.832046718097;6488.156539649815;6494.967525162533;6504.15445992018;6520.217573891164;6520.543561950808;5221.321228240305;5746.2529871116485;5894.0274854648505;6277.5605707600025;6288.402601752826;5434.347878254634;5710;5800.1299805029585;6238.469730072813;6288.622682639057;5087.720175390389;6090.756369745161;6146.692665366803;6167.722452489559;6171.352008039091;5936.673482958306;6199.380061993765;6275.246970363585;6308.400701933309;6337.7781777637565;5460.808871689631;6204.13142160089;6236;6245.344238854855;6266.561340706289 05:06:33 INFO - PROCESS | 1662 | |5;yui.html;666.363622740283;718.7412531488663;741.7700512841017;746.7162478258263;752.9360004399625;11855.407229638527;12455.875441245586;15426.305816238282;15439.536813895602;15639.843601563982;14088.71822563548;14172.71654566908;14263.500777472791;14270.785938210909;14287.92856035721;723.2803360656128;740.3780824107748;889.7820034091641;908.1735620585268;911.0752586125055;888.0764005434373;925.3060204846365;928.0394791390912;929.45162354211;934.6448349627132;980.6175591519313;980.7106903463496;983.4296108257194;987.9950600246989;989.9902099858141;753.932146106853;879.4371602174614;932.3892850183141;967.7129417874211;980.3137803537517;725.0031207090251;726.3063774095726;727.0620899040232;728.035353157069;728.5155371677828;15393.153376564285;15630;15644.687106257925;15666.608334791721;15705.293261803246;13019.934900325532;13025.218486890817;13060.346982650924;13239.338033098307;13409.932950335284;9981.750456238653;10250.436226007641;10254.179665626732;10580.418077005761;10599.947000265027;3517.6482351764876;3543.3799085159994;3579.194681196736;3594.802285874276;3687.520622319108;91160;91688.08311916862;92236.53881730548;95511;98637;8499.830003399897;9338.719838404859;9571.760705982268;9918.206543476505;10486.318389304672;20893.477663058242;21036.948152592307;21051.78948210498;21074;21103.366898993056;7426.7400640978385;7625.199354067854;7664.578448185292;7772.300492955661;7895.210478952059;4076.7961601919787;4083.3262511685834;4096.73371230875;4110.938335924963;4123.484564429446;3560.003199104255;3637.94543081854;3640.21735326906;3640.508531348287;3644;602.9246344206974;627.3757611176908;630.2311180359953;654.5908806995627;689.0938415982966;484.5881001149066;527.7651445106967;571.1546910572293;578.6210032428766;594.1029046140359;7806.750919852796;7977.800554986055;8055.194480552015;8204.630791614392;8325.8334833303;500.99050413416995;515.487090345109;553.2669213292404;582.2925145947694;583.5040215816522;3881.650651441327;3889.183271512957;3907.433422153829;4036.7577945323374;4214.852480163179;95167.52416237874;95990.52004739932;96289;97762;99531;79447.60276198582;84708.72936905951;84828;85606;90208.5489572561;55609.72195139079;73032.90450643138;74003.62998184975;75057;75266.24733752593;7514.910338001068;7647.48144008938;8070.1122876484715;8228.136045715113;8264.83470330602;42715.14569708526;46043.33044197891;46615.30077048779;46875.53124468712;47606;62248.68875655593;69316;69632;69651.30348696651;69792.65103674449;10984.450777461256;11000.944995274973;11119.332840029625;11213.60752373663;11229.157813163882;11836.289822610672;12063.879361206276;12583.245005299712;12938.223706577635;12977.935110324388;7873.645685944031;11190.440477976232;11305.830412543653;11507.194496385173;11766.352850593299;11102.333859968428;11305.943470282597;11472.967432931076;11594;11620.302781833117;9710.6115755369;10893.509792059218;11013.339199648046;11299;11500.53997840077;9460.95269523648;9478.715638530855;9652.398735995494;9868.802623947337;10035.241983321097;9546.522673866419;9709.417434953926;9714.659986900424;9744.658936937456;9792.902070979198 05:06:33 INFO - PROCESS | 1662 | __end_tp_report 05:06:33 INFO - PROCESS | 1662 | __start_cc_report 05:06:33 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1218 05:06:33 INFO - PROCESS | 1662 | __end_cc_report 05:06:33 INFO - PROCESS | 1662 | __startTimestamp1461585993942__endTimestamp 05:06:33 INFO - PROCESS | 1662 | 05:06:33 INFO - PROCESS | 1662 | ------- Summary: start ------- 05:06:33 INFO - PROCESS | 1662 | Number of tests: 6 05:06:33 INFO - PROCESS | 1662 | 05:06:33 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5701.50 Median:5954.88 stddev:658.21 (11.1%) stddev-sans-first:632.82 05:06:33 INFO - PROCESS | 1662 | Values: 3305.4 3427.6 3449.7 3451.2 3480.7 5567.1 5657.4 5677.2 5689.1 5689.4 5616.8 5674.1 5676.0 5676.0 5691.4 5112.5 5116.9 5143.7 5159.4 5216.5 5203.2 5206.5 5213.4 5214.9 5223.6 5128.0 5156.8 5182.2 5195.5 5243.6 5182.9 5213.3 5218.5 5234.6 5260.6 4088.3 5110.2 5149.0 5164.0 5167.4 5554.8 5561.6 5575.7 5664.5 5687.2 5148.8 6206.8 6217.3 6223.3 6226.2 6161.1 6205.4 6254.7 6264.7 6276.1 5784.8 5793.0 5814.7 5852.1 5852.8 6251.2 6276.2 6277.0 6292.8 6300.9 6092.7 6126.9 6173.5 6181.5 6191.5 6226.1 6254.7 6260.2 6263.2 6269.7 6206.7 6206.8 6219.6 6220.7 6238.8 6120.2 6165.3 6182.2 6207.6 6246.8 4852.3 4854.2 4855.7 4856.3 4859.6 4328.2 4394.3 4406.1 4417.9 4422.7 5650.7 5740.8 5759.3 5759.9 5760.6 6153.0 6233.9 6260.8 6264.2 6275.7 4907.3 5662.9 5684.8 5693.9 5695.4 5326.0 5372.1 5388.5 5402.9 5438.9 6265.0 6283.4 6308.6 6313.4 6332.4 6255.4 6263.5 6266.5 6274.0 6284.6 6193.6 6260.0 6276.2 6289.7 6294.4 6104.6 6111.1 6151.4 6196.8 6200.1 5571.2 6240.7 6241.6 6244.6 6298.7 4658.9 6071.5 6088.9 6115.2 6124.0 5943.4 6044.2 6101.4 6110.7 6135.4 5944.9 5954.9 5957.9 5965.0 5996.7 5984.3 6059.0 6114.3 6123.8 6131.4 5008.6 5863.2 5960.6 6007.6 6067.9 05:06:33 INFO - PROCESS | 1662 | 05:06:33 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:70226.66 Median:3415.91 stddev:157526.29 (4611.5%) stddev-sans-first:157881.25 05:06:33 INFO - PROCESS | 1662 | Values: 518.9 527.8 562.1 580.5 615.6 30550.1 31117.7 31175.2 31178.7 38709.8 42398.0 43251.0 43286.1 43289.0 43294.4 9581.3 9847.6 9854.5 9862.2 9880.8 8576.1 8852.0 9584.1 9723.0 11531.7 11543.7 11652.5 11678.7 11680.1 11681.2 11466.8 11564.7 11569.5 11581.7 11621.0 8576.2 11503.8 11800.5 11816.8 11833.3 38609.6 39188.0 39215.8 39229.2 39339.4 3187.0 3268.0 3357.4 3429.3 3539.5 23073.9 27534.9 27689.7 28368.6 29188.0 2685.7 2743.2 2744.0 2819.3 2848.9 491507.0 493326.5 494224.0 494556.5 495115.0 29772.4 64969.7 66221.0 66297.0 66333.7 35806.0 35978.6 36031.0 36060.8 36098.3 12843.7 14848.5 15010.4 15182.2 15184.4 11788.2 12881.9 12935.1 12972.0 12977.3 7688.7 7774.5 7785.8 7805.3 8065.4 3330.7 3358.6 3369.5 3395.7 3402.5 875.3 998.9 1028.5 1076.2 1080.4 28743.0 28816.9 29137.6 29454.3 29756.3 1080.3 1081.6 1155.8 1176.8 1222.9 14294.0 15028.5 16134.4 16454.3 16732.0 646188.0 647041.0 657143.0 657620.0 659792.7 54867.0 61842.4 62907.7 63755.0 63869.4 1459.0 1466.0 1466.1 1478.7 1482.3 28641.0 28865.0 29089.1 29527.6 29682.3 54343.0 55984.0 55990.0 56041.7 56094.0 585074.0 609162.0 609556.0 610030.0 610487.0 27140.1 27446.2 27533.7 27562.2 27579.0 45678.5 45956.0 46376.3 46854.3 47958.3 37858.0 38360.2 38632.8 39397.8 39526.0 36971.0 37139.6 37198.3 37886.1 38273.0 37135.0 37558.0 37802.0 37846.6 38348.8 31401.8 32518.3 32835.7 33449.7 33533.5 23884.0 24677.3 24733.9 25220.5 25378.0 05:06:33 INFO - PROCESS | 1662 | 05:06:33 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:34868.08 Median:13516.96 stddev:100643.20 (744.6%) stddev-sans-first:100892.24 05:06:33 INFO - PROCESS | 1662 | Values: 395.8 396.7 402.7 406.0 409.5 15929.9 19884.0 20627.2 20674.4 21043.8 19848.3 20230.9 20615.6 20629.7 20676.4 5436.7 5557.7 5885.3 5885.8 5892.3 5582.7 5638.6 5791.6 5796.7 5801.9 5744.7 5785.9 5799.4 5805.0 5809.3 5644.5 5695.9 5784.5 5801.7 5810.2 5096.7 5139.4 5140.5 5141.3 5141.8 13867.8 23552.7 23722.9 24587.0 25194.6 1873.8 2049.0 2124.0 2219.6 2446.9 9289.4 10127.5 13564.7 14715.7 14891.4 1720.5 1783.0 1790.3 1792.9 1811.1 79333.4 81176.2 106162.0 128533.4 128907.0 11094.0 11612.2 11724.4 11725.5 11771.6 14948.0 14981.9 15007.6 15013.6 15038.5 8468.7 8503.7 8528.3 8551.0 8668.9 8409.1 8411.5 8451.0 8510.2 8548.1 3761.8 4538.2 4538.4 4557.0 4608.7 1624.0 1703.8 1887.3 1935.5 1941.8 861.7 925.0 958.3 968.7 995.7 19204.5 19676.3 20051.9 20066.0 20098.3 919.0 937.4 937.7 943.0 973.5 8346.8 10741.2 10745.2 10748.9 10753.6 584817.0 595234.0 603543.0 614242.0 614675.0 135105.3 137087.0 137549.3 138010.3 145809.3 1017.0 1027.2 1028.8 1029.0 1054.9 17853.7 18151.0 18210.0 18433.9 18476.0 52983.7 53342.7 53976.7 54204.7 55523.4 73798.6 76247.0 76335.0 78523.0 78635.6 6603.8 7162.4 7900.2 8056.2 8068.5 9089.8 11711.2 12215.6 12532.6 12924.2 9257.7 10914.3 11296.5 11306.8 11401.0 10756.5 10857.3 11056.3 11152.8 11267.5 10879.5 11023.6 11077.2 11388.7 11565.7 6549.3 8014.4 8171.3 8243.1 8453.2 8375.2 10067.6 10238.6 10508.9 11306.7 05:06:33 INFO - PROCESS | 1662 | 05:06:33 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5779.01 Median:2551.24 stddev:3273.02 (128.3%) stddev-sans-first:3265.73 05:06:33 INFO - PROCESS | 1662 | Values: 1409.6 1588.0 1596.2 1858.9 1978.5 7983.2 8807.6 9050.3 9070.3 9078.6 8669.7 8890.2 8903.1 8909.7 8911.7 2163.8 2391.9 2445.6 2477.8 2489.7 2452.2 2478.8 2481.6 2483.0 2484.7 2401.6 2461.7 2462.3 2463.7 2465.6 2158.4 2176.3 2428.7 2430.6 2440.4 2434.8 2442.4 2468.0 2468.0 2473.8 8739.2 8795.2 8798.0 8798.2 8806.8 5179.8 5391.0 5400.4 5407.6 5515.8 3580.0 3590.9 3605.0 3649.2 3659.7 1008.9 1021.7 1023.6 1031.3 1032.3 11842.1 11880.6 11881.5 11883.9 11887.3 4563.0 4629.5 4804.2 4836.9 4878.5 10026.1 10030.0 10032.4 10041.3 10061.1 5408.0 5409.3 5411.5 5453.4 5459.9 4440.4 4440.5 4502.2 4614.3 4920.6 3907.3 3990.5 4062.6 4071.2 4093.7 1143.9 1296.2 1337.7 1343.1 1357.2 727.9 896.4 928.7 943.9 953.5 5851.2 5914.7 6061.0 6062.3 6129.4 461.6 473.0 476.2 485.2 485.4 3819.5 3943.5 4024.3 4102.7 4112.9 10516.8 10955.7 10982.9 11029.9 11045.1 10999.2 11000.5 11003.9 11015.4 11021.4 7710.5 8159.5 8229.8 8239.1 8253.0 4904.4 5993.1 6079.8 6151.3 6187.1 10034.6 10142.0 10195.6 10262.1 10310.6 9959.9 10257.3 10388.9 10535.0 10574.2 6913.9 7205.1 7445.5 7611.9 7778.0 8459.7 8466.4 8549.4 8554.0 8559.9 7358.7 7465.9 7586.2 7588.2 7612.0 7262.9 7335.4 7343.2 7454.3 7489.6 7026.2 7135.9 7175.5 7177.0 7180.4 4792.9 4934.5 4976.9 5081.4 5153.1 4928.0 5009.3 5046.8 5097.8 5266.8 05:06:33 INFO - PROCESS | 1662 | 05:06:33 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:5010.71 Median:5730.24 stddev:1609.16 (28.1%) stddev-sans-first:1574.52 05:06:33 INFO - PROCESS | 1662 | Values: 310.1 315.7 323.6 329.6 331.4 5335.7 5805.4 5858.0 5872.0 5915.0 4777.5 5563.3 5566.2 5768.4 5771.6 3951.3 4320.4 4361.8 4379.2 4396.3 3737.5 4111.1 4214.7 4328.5 4381.1 3582.6 3846.2 4321.6 4321.7 4337.4 3202.6 3937.6 3942.3 4156.5 4272.8 3732.8 3733.0 3739.2 3744.3 3750.4 4665.8 5319.3 5678.7 5718.5 5742.0 4189.8 4357.1 4925.2 4997.6 5023.7 6224.3 6225.6 6243.1 6245.0 6248.4 3638.6 3888.7 4135.5 4140.3 4161.6 5658.7 5699.7 6525.7 6587.8 6590.0 4211.1 4214.8 4217.3 4225.6 4229.6 4013.2 4233.8 4277.1 4281.7 4293.4 4983.8 4999.4 5013.2 5021.3 5029.5 6249.2 6278.4 6280.7 6303.8 6316.2 1323.1 1443.0 1448.8 1527.7 1536.6 774.0 901.0 984.6 992.5 1023.5 4589.9 4607.3 5782.9 5826.4 5853.3 5259.9 5821.5 6104.6 6242.0 6413.3 5563.2 5804.3 5808.7 5812.9 5853.2 3117.5 3354.4 3378.3 3385.8 3406.3 5876.4 6414.6 6681.5 6695.2 6703.2 6493.4 6551.7 6574.6 6587.5 6615.5 6586.7 6666.6 6703.2 6706.3 6707.5 6148.9 6536.0 6554.9 6576.6 6588.9 6352.6 6433.7 6446.8 6476.3 6492.7 5064.5 6401.7 6648.4 6804.4 6831.4 3927.9 4004.6 4096.8 4124.3 4198.8 6488.2 6495.0 6504.2 6520.2 6520.5 5221.3 5746.3 5894.0 6277.6 6288.4 5434.3 5710.0 5800.1 6238.5 6288.6 5087.7 6090.8 6146.7 6167.7 6171.4 5936.7 6199.4 6275.2 6308.4 6337.8 5460.8 6204.1 6236.0 6245.3 6266.6 05:06:33 INFO - PROCESS | 1662 | 05:06:33 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:18898.88 Median:31425.81 stddev:27433.84 (87.3%) stddev-sans-first:27476.64 05:06:33 INFO - PROCESS | 1662 | Values: 666.4 718.7 741.8 746.7 752.9 11855.4 12455.9 15426.3 15439.5 15639.8 14088.7 14172.7 14263.5 14270.8 14287.9 723.3 740.4 889.8 908.2 911.1 888.1 925.3 928.0 929.5 934.6 980.6 980.7 983.4 988.0 990.0 753.9 879.4 932.4 967.7 980.3 725.0 726.3 727.1 728.0 728.5 15393.2 15630.0 15644.7 15666.6 15705.3 13019.9 13025.2 13060.3 13239.3 13409.9 9981.8 10250.4 10254.2 10580.4 10599.9 3517.6 3543.4 3579.2 3594.8 3687.5 91160.0 91688.1 92236.5 95511.0 98637.0 8499.8 9338.7 9571.8 9918.2 10486.3 20893.5 21036.9 21051.8 21074.0 21103.4 7426.7 7625.2 7664.6 7772.3 7895.2 4076.8 4083.3 4096.7 4110.9 4123.5 3560.0 3637.9 3640.2 3640.5 3644.0 602.9 627.4 630.2 654.6 689.1 484.6 527.8 571.2 578.6 594.1 7806.8 7977.8 8055.2 8204.6 8325.8 501.0 515.5 553.3 582.3 583.5 3881.7 3889.2 3907.4 4036.8 4214.9 95167.5 95990.5 96289.0 97762.0 99531.0 79447.6 84708.7 84828.0 85606.0 90208.5 55609.7 73032.9 74003.6 75057.0 75266.2 7514.9 7647.5 8070.1 8228.1 8264.8 42715.1 46043.3 46615.3 46875.5 47606.0 62248.7 69316.0 69632.0 69651.3 69792.7 10984.5 11000.9 11119.3 11213.6 11229.2 11836.3 12063.9 12583.2 12938.2 12977.9 7873.6 11190.4 11305.8 11507.2 11766.4 11102.3 11305.9 11473.0 11594.0 11620.3 9710.6 10893.5 11013.3 11299.0 11500.5 9461.0 9478.7 9652.4 9868.8 10035.2 9546.5 9709.4 9714.7 9744.7 9792.9 05:06:33 INFO - PROCESS | 1662 | -------- Summary: end -------- 05:06:33 INFO - PROCESS | 1662 | 05:06:34 INFO - TEST-INFO | 1662: exit 0 05:06:34 INFO - TEST-OK | dromaeo_css | took 1115926ms 05:06:34 INFO - TEST-START | kraken 05:06:34 INFO - operating with platform_type : mac_ 05:06:34 INFO - Initialising browser for kraken test... 05:06:34 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpUhBd8z/profile http://localhost:49270/getInfo.html) 05:06:36 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 05:06:36 INFO - PROCESS | 1668 | colorDepth:24 05:06:36 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 05:06:36 INFO - PROCESS | 1668 | __metrics 05:06:36 INFO - PROCESS | 1668 | 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" 05:06:36 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:06:37 INFO - TEST-INFO | 1668: exit 0 05:06:37 INFO - Browser initialized. 05:06:37 INFO - Running cycle 1/1 for kraken test... 05:06:37 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/tmpUhBd8z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:49 INFO - PROCESS | 1670 | RSS: Main: 238882816 05:06:49 INFO - PROCESS | 1670 | 05:06:51 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 05:06:52 INFO - PROCESS | 1670 | RSS: Main: 285958144 05:06:52 INFO - PROCESS | 1670 | 05:06:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 05:06:56 INFO - PROCESS | 1670 | RSS: Main: 298848256 05:06:56 INFO - PROCESS | 1670 | 05:06:59 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 05:07:00 INFO - PROCESS | 1670 | RSS: Main: 384876544 05:07:00 INFO - PROCESS | 1670 | 05:07:02 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 05:07:02 INFO - PROCESS | 1670 | RSS: Main: 287461376 05:07:02 INFO - PROCESS | 1670 | 05:07:04 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:07:05 INFO - PROCESS | 1670 | RSS: Main: 291614720 05:07:05 INFO - PROCESS | 1670 | 05:07:08 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 05:07:08 INFO - PROCESS | 1670 | RSS: Main: 330424320 05:07:08 INFO - PROCESS | 1670 | 05:07:11 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 05:07:12 INFO - PROCESS | 1670 | RSS: Main: 330952704 05:07:12 INFO - PROCESS | 1670 | 05:07:15 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 05:07:15 INFO - PROCESS | 1670 | RSS: Main: 329367552 05:07:15 INFO - PROCESS | 1670 | 05:07:17 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:07:17 INFO - PROCESS | 1670 | RSS: Main: 320028672 05:07:17 INFO - PROCESS | 1670 | 05:07:19 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:07:20 INFO - PROCESS | 1670 | RSS: Main: 327458816 05:07:20 INFO - PROCESS | 1670 | 05:07:22 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:07:23 INFO - PROCESS | 1670 | RSS: Main: 330063872 05:07:23 INFO - PROCESS | 1670 | 05:07:26 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:07:26 INFO - PROCESS | 1670 | RSS: Main: 334385152 05:07:26 INFO - PROCESS | 1670 | 05:07:29 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:07:30 INFO - PROCESS | 1670 | RSS: Main: 332177408 05:07:30 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 05:07:32 INFO - PROCESS | 1670 | RSS: Main: 397041664 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | __start_tp_report 05:07:32 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 05:07:32 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 05:07:32 INFO - PROCESS | 1670 | |i|pagename|runs| 05:07:32 INFO - PROCESS | 1670 | |0;ai-astar;112;110;112;111;114;114;119;113;114;112 05:07:32 INFO - PROCESS | 1670 | |1;audio-beat-detection;157;122;143;168;119;157;158;145;182;120 05:07:32 INFO - PROCESS | 1670 | |2;audio-dft;187;186;186;183;186;180;181;182;185;198 05:07:32 INFO - PROCESS | 1670 | |3;audio-fft;80;76;86;91;57;55;58;77;85;56 05:07:32 INFO - PROCESS | 1670 | |4;audio-oscillator;91;87;133;78;78;81;122;78;78;127 05:07:32 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;87;84;87;84;85;83;87;84;88;83 05:07:32 INFO - PROCESS | 1670 | |6;imaging-darkroom;142;93;94;93;92;93;93;93;93;92 05:07:32 INFO - PROCESS | 1670 | |7;imaging-desaturate;84;86;83;85;86;83;84;85;86;83 05:07:32 INFO - PROCESS | 1670 | |8;json-parse-financial;58;61;59;59;62;59;60;80;61;57 05:07:32 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;65;64;73;76;77;72;69;69;68;69 05:07:32 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;82;84;90;102;83;78;79;82;84;96 05:07:32 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;140;158;159;156;138;135;134;136;171;146 05:07:32 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;171;173;171;163;170;171;173;166;169;169 05:07:32 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;68;70;69;68;69;66;70;70;67;66 05:07:32 INFO - PROCESS | 1670 | __end_tp_report 05:07:32 INFO - PROCESS | 1670 | __start_cc_report 05:07:32 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2110 05:07:32 INFO - PROCESS | 1670 | __end_cc_report 05:07:32 INFO - PROCESS | 1670 | __startTimestamp1461586052314__endTimestamp 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | ------- Summary: start ------- 05:07:32 INFO - PROCESS | 1670 | Number of tests: 14 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:113.10 Median:113.50 stddev:2.47 (2.2%) stddev-sans-first:2.59 05:07:32 INFO - PROCESS | 1670 | Values: 112.0 110.0 112.0 111.0 114.0 114.0 119.0 113.0 114.0 112.0 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:147.10 Median:157.00 stddev:21.46 (13.7%) stddev-sans-first:22.46 05:07:32 INFO - PROCESS | 1670 | Values: 157.0 122.0 143.0 168.0 119.0 157.0 158.0 145.0 182.0 120.0 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:185.40 Median:186.00 stddev:5.04 (2.7%) stddev-sans-first:5.31 05:07:32 INFO - PROCESS | 1670 | Values: 187.0 186.0 186.0 183.0 186.0 180.0 181.0 182.0 185.0 198.0 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:72.10 Median:78.50 stddev:14.13 (18.0%) stddev-sans-first:14.70 05:07:32 INFO - PROCESS | 1670 | Values: 80.0 76.0 86.0 91.0 57.0 55.0 58.0 77.0 85.0 56.0 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:78.00 stddev:22.68 (29.1%) stddev-sans-first:24.00 05:07:32 INFO - PROCESS | 1670 | Values: 91.0 87.0 133.0 78.0 78.0 81.0 122.0 78.0 78.0 127.0 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:85.20 Median:86.00 stddev:1.87 (2.2%) stddev-sans-first:1.87 05:07:32 INFO - PROCESS | 1670 | Values: 87.0 84.0 87.0 84.0 85.0 83.0 87.0 84.0 88.0 83.0 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:97.80 Median:93.00 stddev:15.54 (16.7%) stddev-sans-first:0.60 05:07:32 INFO - PROCESS | 1670 | Values: 142.0 93.0 94.0 93.0 92.0 93.0 93.0 93.0 93.0 92.0 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:1.27 (1.5%) stddev-sans-first:1.33 05:07:32 INFO - PROCESS | 1670 | Values: 84.0 86.0 83.0 85.0 86.0 83.0 84.0 85.0 86.0 83.0 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:61.60 Median:60.50 stddev:6.64 (11.0%) stddev-sans-first:6.91 05:07:32 INFO - PROCESS | 1670 | Values: 58.0 61.0 59.0 59.0 62.0 59.0 60.0 80.0 61.0 57.0 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.50 stddev:4.29 (6.1%) stddev-sans-first:4.12 05:07:32 INFO - PROCESS | 1670 | Values: 65.0 64.0 73.0 76.0 77.0 72.0 69.0 69.0 68.0 69.0 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:83.50 stddev:7.70 (9.2%) stddev-sans-first:8.03 05:07:32 INFO - PROCESS | 1670 | Values: 82.0 84.0 90.0 102.0 83.0 78.0 79.0 82.0 84.0 96.0 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:151.00 stddev:12.85 (8.5%) stddev-sans-first:13.36 05:07:32 INFO - PROCESS | 1670 | Values: 140.0 158.0 159.0 156.0 138.0 135.0 134.0 136.0 171.0 146.0 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.60 Median:171.00 stddev:3.10 (1.8%) stddev-sans-first:3.24 05:07:32 INFO - PROCESS | 1670 | Values: 171.0 173.0 171.0 163.0 170.0 171.0 173.0 166.0 169.0 169.0 05:07:32 INFO - PROCESS | 1670 | 05:07:32 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:1.57 (2.3%) stddev-sans-first:1.66 05:07:32 INFO - PROCESS | 1670 | Values: 68.0 70.0 69.0 68.0 69.0 66.0 70.0 70.0 67.0 66.0 05:07:32 INFO - PROCESS | 1670 | -------- Summary: end -------- 05:07:32 INFO - PROCESS | 1670 | 05:07:33 INFO - TEST-INFO | 1670: exit 0 05:07:33 INFO - TEST-OK | kraken | took 58622ms 05:07:33 INFO - SUITE-END | took 1174s 05:07:33 INFO - Completed test suite (00:19:34) 05:07:33 INFO - javascript benchmark 05:07:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3305.438075527161, 3427.5715535558056, 3449.689527942488, 3451.1889705919107, 3480.6867381935635, 5567.053600887849, 5657.377688454277, 5677.176809362645, 5689.089745640698, 5689.402612725661, 5616.803411880585, 5674.09214525676, 5676.0, 5676.0, 5691.430856914317, 5112.539871411572, 5116.948830511703, 5143.665661731994, 5159.355080614923, 5216.478352164791, 5203.19350500673, 5206.479352064782, 5213.426523082458, 5214.895702085956, 5223.555997740177, 5127.9743601282125, 5156.767945442464, 5182.196759502283, 5195.480451954793, 5243.606729495284, 5182.870428239286, 5213.2701421800975, 5218.504242096995, 5234.581233501311, 5260.579153667697, 4088.325426304656, 5110.233464980245, 5148.9702059588235, 5164.018836421105, 5167.431582525919, 5554.805581804658, 5561.58288128392, 5575.69333686647, 5664.546836253131, 5687.232223649796, 5148.819791307286, 6206.751729930843, 6217.285012223561, 6223.25320961483, 6226.190595222637, 6161.137440758297, 6205.379462053804, 6254.655993920333, 6264.749410023594, 6276.121343011982, 5784.797532086357, 5792.9710351447975, 5814.651120932757, 5852.092925596497, 5852.765889364463, 6251.249850017936, 6276.152719382825, 6277.0, 6292.811215663537, 6300.905486417708, 6092.664903430265, 6126.9387306127255, 6173.475254603409, 6181.474574661204, 6191.473724733358, 6226.097215903668, 6254.6872656366995, 6260.186175797117, 6263.217097862767, 6269.68651567429, 6206.658633775095, 6206.782762603287, 6219.564630475823, 6220.688965551704, 6238.844028899223, 6120.234970628671, 6165.32181460039, 6182.165407669997, 6207.6275423474735, 6246.75012999484, 4852.296417019513, 4854.199057155601, 4855.660103792771, 4856.3201151838775, 4859.611231101503, 4328.220920234388, 4394.2969124939855, 4406.074724307867, 4417.867463976086, 4422.7346359218545, 5650.660960342392, 5740.799072032459, 5759.308882933991, 5759.942400576025, 5760.6255593386295, 6153.046277826944, 6233.906491402633, 6260.843478912972, 6264.248290205113, 6275.748970041147, 4907.312976183337, 5662.943370566324, 5684.801031963944, 5693.914591281134, 5695.430456954271, 5326.0, 5372.086745253317, 5388.5419739321815, 5402.8919421611345, 5438.8912221755345, 6264.968675156595, 6283.403076707706, 6308.621482711052, 6313.43179113882, 6332.398422149889, 6255.374462553708, 6263.530235232284, 6266.498680105489, 6274.0, 6284.622922624657, 6193.628382297076, 6259.96870015647, 6276.246850377984, 6289.748410063729, 6294.402031806971, 6104.641056839945, 6111.113888486234, 6151.415615516519, 6196.845078873064, 6200.131981522501, 5571.192177134212, 6240.687965601611, 6241.594296370721, 6244.562880598314, 6298.748050077946, 4658.93011604833, 6071.544634152545, 6088.939110608837, 6115.174451448997, 6124.020156775073, 5943.435373639497, 6044.15381846533, 6101.368489499298, 6110.680268050674, 6135.417135372132, 5944.910826337695, 5954.880902381841, 5957.940420595739, 5965.04559270532, 5996.700164991646, 5984.31180414258, 6059.0, 6114.266288045463, 6123.785667501616, 6131.4481696645735, 5008.574271186844, 5863.20846685692, 5960.552958528215, 6007.609505382136, 6067.878642427215], "value": 5660.552036451187, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.914379127444, 527.7545941137371, 562.0725802425999, 580.4717706886734, 615.6152404747033, 30550.08349749508, 31117.688823111763, 31175.220619484524, 31178.68821311789, 38709.80645096778, 42398.0, 43251.0, 43286.134277314435, 43289.0, 43294.35058474125, 9581.329306948517, 9847.55685994132, 9854.458004809732, 9862.161716254128, 9880.752981175492, 8576.05663377028, 8851.95574022129, 9584.137427631511, 9722.951385243065, 11531.711707207303, 11543.711407214803, 11652.475638596283, 11678.708032299175, 11680.065594752441, 11681.240719353258, 11466.827997580043, 11564.653060408202, 11569.479373428174, 11581.710457238552, 11621.012213961823, 8576.185262400062, 11503.769924601545, 11800.46897889597, 11816.763664726743, 11833.349165795878, 38609.613903861165, 39188.0, 39215.8039209805, 39229.21541569153, 39339.40990885139, 3186.9840650796828, 3267.9999999999764, 3357.3788849062757, 3429.2798512312443, 3539.486774417721, 23073.884630576908, 27534.862325688242, 27689.723102768916, 28368.574471382944, 29188.0, 2685.7314268572986, 2743.245607457965, 2744.0395861448533, 2819.2669905824428, 2848.943021139586, 491507.0, 493326.53336733446, 494224.0, 494556.5272173652, 495115.0, 29772.40455190884, 64969.675151624884, 66221.0, 66297.0, 66333.66833165899, 35806.0, 35978.64021359805, 36031.0, 36060.81969590135, 36098.278034439165, 12843.743125137446, 14848.480303189337, 15010.399584016517, 15182.240887955559, 15184.392624295126, 11788.23376480526, 12881.871181288254, 12935.094543382062, 12972.0, 12977.286249256382, 7688.730894418669, 7774.494657847222, 7785.766427007199, 7805.336546393507, 8065.39509536787, 3330.733541316689, 3358.6473420290517, 3369.4777309517062, 3395.728341732705, 3402.5406570113214, 875.299760191844, 998.9350692204983, 1028.5268776362807, 1076.1659713721772, 1080.4273734920505, 28742.99399521007, 28816.855915720287, 29137.56293655597, 29454.263643409085, 29756.2560935974, 1080.308602494404, 1081.6376513867942, 1155.7688462307572, 1176.758764453285, 1222.914395992272, 14294.0, 15028.526647332885, 16134.435294764628, 16454.259558319904, 16731.996080235225, 646188.0, 647041.0, 657143.0, 657620.0, 659792.7010365013, 54867.0, 61842.38157618456, 62907.6854615724, 63755.043674344925, 63869.40326491782, 1458.9781153282709, 1465.9560213193622, 1466.0543949152736, 1478.6672998575234, 1482.3107255126201, 28641.0, 28865.0, 29089.12732618025, 29527.557086643286, 29682.257943551584, 54343.0, 55984.0, 55990.0, 56041.719791400785, 56094.0, 585074.0, 609162.0, 609556.0, 610030.0, 610487.0, 27140.050098246465, 27446.17661470159, 27533.724662753917, 27562.173134805988, 27579.0, 45678.54321456743, 45956.0, 46376.30435543537, 46854.29718554293, 47958.28062578994, 37858.0, 38360.2327953445, 38632.80683596564, 39397.80301098476, 39526.0, 36971.0, 37139.6286037147, 37198.25603487969, 37886.052848679006, 38273.04317392087, 37135.0, 37558.0, 37802.0, 37846.62153378431, 38348.80825595854, 31401.8429907849, 32518.34963300768, 32835.68657253683, 33449.66550334466, 33533.49699754457, 23884.0, 24677.259682209562, 24733.87633061823, 25220.495590088463, 25378.0], "value": 18952.12764810757, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.81990194461525, 396.71832998571017, 402.7080366734118, 406.0498433665223, 409.4615580511627, 15929.920350398246, 19884.0, 20627.17491300348, 20674.37976860695, 21043.789562104375, 19848.305309314175, 20230.89884550575, 20615.587688246225, 20629.690554641693, 20676.37970860873, 5436.728163591824, 5557.665780372233, 5885.264341957255, 5885.7939972100985, 5892.322382925976, 5582.748776305056, 5638.577106716992, 5791.623544469596, 5796.681182534959, 5801.883962320751, 5744.6840423776675, 5785.855353616151, 5799.449052340021, 5805.042168042268, 5809.331926828427, 5644.520215781664, 5695.943040569624, 5784.4504772046585, 5801.6519008859605, 5810.157527158581, 5096.745162741885, 5139.383274007106, 5140.537351638371, 5141.331626888518, 5141.820036298712, 13867.791983120262, 23552.704601246925, 23722.93246803898, 24587.0, 25194.622080668803, 1873.7657792775904, 2049.036952632275, 2123.9804893651135, 2219.5782801267706, 2446.911124549576, 9289.396189247676, 10127.493625318704, 13564.660883477993, 14715.705685886222, 14891.404343826234, 1720.4924547258588, 1783.0460703523552, 1790.3196785221533, 1792.9193186306647, 1811.0582497101566, 79333.38939537066, 81176.18823811805, 106162.0, 128533.35733321274, 128907.0, 11094.0, 11612.245204061708, 11724.413779311, 11725.472353744102, 11771.64567086586, 14948.0, 14981.850181498046, 15007.624809379635, 15013.624659383384, 15038.473653422077, 8468.661253549912, 8503.702370417006, 8528.275096616857, 8550.957245213734, 8668.8699669505, 8409.075001749803, 8411.537365444838, 8450.957745211235, 8510.234078932926, 8548.102449242864, 3761.755485893408, 4538.228501154812, 4538.432695913011, 4556.997460558672, 4608.677392582487, 1623.959401014984, 1703.769991051217, 1887.3488646416963, 1935.5064458563259, 1941.8155275248828, 861.7156338408311, 925.0102390442297, 958.3387462650749, 968.651285537206, 995.7012896131132, 19204.519887002938, 19676.3113291037, 20051.899740501496, 20065.996700164935, 20098.296559620343, 918.9540522973824, 937.3536941298355, 937.7468083617385, 942.9811403771886, 973.4889183178747, 8346.791330216673, 10741.248112632196, 10745.194110441591, 10748.94625526883, 10753.623623173151, 584817.0, 595234.0, 603543.0, 614242.0, 614675.0, 135105.324473377, 137087.0, 137549.31225343808, 138010.3099484496, 145809.27095364456, 1017.0338178730236, 1027.1679939249082, 1028.8199565076081, 1029.0224286927448, 1054.8997845204694, 17853.73219401684, 18151.0, 18209.99845008537, 18433.90783046076, 18476.0, 52983.73508132435, 53342.73328633332, 53976.730116349165, 54204.72897635487, 55523.44476555183, 73798.63100684462, 76247.0, 76335.0, 78523.0, 78635.60682196553, 6603.80188594343, 7162.355388014896, 7900.249476299742, 8056.194380561897, 8068.47554908929, 9089.77275568116, 11711.180217385043, 12215.572454964034, 12532.624021279376, 12924.159929604546, 9257.67598134062, 10914.345139291669, 11296.548138074386, 11306.830397544209, 11401.030912372542, 10756.51595678212, 10857.34855908648, 11056.281341712764, 11152.776944461228, 11267.492962816857, 10879.456027198767, 11023.61417350421, 11077.224594278323, 11388.658340249805, 11565.653030409101, 6549.312322206099, 8014.438989270695, 8171.346292296721, 8243.093259741543, 8453.196946290092, 8375.246227839523, 10067.597296108073, 10238.590456381959, 10508.947455262674, 11306.717332066435], "value": 9409.830467236436, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.619402761255, 1587.9920600396997, 1596.2178532519063, 1858.8512918966476, 1978.5350442645984, 7983.241592048754, 8807.647694092237, 9050.275977921767, 9070.274378049748, 9078.636854525812, 8669.69656062038, 8890.199882010618, 8903.065178156297, 8909.73270801877, 8911.732648020554, 2163.8485306028583, 2391.9162829300976, 2445.608702607583, 2477.838940468873, 2489.726130125685, 2452.227548322273, 2478.7521247875247, 2481.6153496208026, 2482.9943872731574, 2484.7018357797087, 2401.603735383668, 2461.716902556211, 2462.322861213163, 2463.6797216361847, 2465.593177125781, 2158.363282831561, 2176.3351036457125, 2428.672129262545, 2430.611102223636, 2440.389902524369, 2434.780869721716, 2442.3771938155833, 2467.9506409871883, 2468.0498008266945, 2473.777360037587, 8739.213470787596, 8795.164459376349, 8797.988231353413, 8798.164174403486, 8806.779830504225, 5179.766910489037, 5390.97304513475, 5400.432954539795, 5407.594430417695, 5515.834524964257, 3579.9642003579893, 3590.856365745367, 3604.963950360489, 3649.2048269799375, 3659.652333028358, 1008.8839783424853, 1021.7139201023651, 1023.5649848814224, 1031.2626472072504, 1032.2825636182856, 11842.052635789116, 11880.584179553674, 11881.465334059989, 11883.940580297043, 11887.346195959219, 4562.954370456319, 4629.490756016836, 4804.222437698004, 4836.903261934812, 4878.463369029404, 10026.145163144842, 10030.0, 10032.44821534808, 10041.297109202433, 10061.094501494754, 5408.02655522002, 5409.26974858397, 5411.5129638331955, 5453.372862120907, 5459.863503412367, 4440.35614835847, 4440.511543730187, 4502.16709908668, 4614.2617181250835, 4920.581750551171, 3907.316219661548, 3990.541087774885, 4062.614051665087, 4071.185762847442, 4093.672506199497, 1143.874173840877, 1296.1510210811932, 1337.6990177210243, 1343.0531475309883, 1357.1924704800629, 727.8762610356252, 896.3546246702364, 928.6888892221046, 943.9008904065112, 953.5422996961497, 5851.239338885917, 5914.733836977346, 6061.0, 6062.302555284527, 6129.417705317987, 461.61455184920965, 472.98581042568776, 476.2166236540871, 485.1752021563356, 485.36902027364283, 3819.52255968004, 3943.4873466449176, 4024.3158663027357, 4102.692298077656, 4112.856050038295, 10516.842247366296, 10955.671329860117, 10982.890171098346, 11029.889701103048, 11045.116390688725, 10999.23005389615, 11000.504977276041, 11003.944980275048, 11015.394153321644, 11021.393823339793, 7710.498817576839, 8159.46963447374, 8229.83540329202, 8239.052508961546, 8253.009638843258, 4904.411470623548, 5993.0710739835395, 6079.787207447718, 6151.292601350902, 6187.133801267736, 10034.598616055277, 10142.0, 10195.643152489627, 10262.076413122855, 10310.639127630497, 9959.900400995897, 10257.281990260908, 10388.896111039096, 10534.999175078356, 10574.15406767472, 6913.861722765617, 7205.099362579677, 7445.478816482794, 7611.885821712568, 7778.0, 8459.746207613782, 8466.449680770731, 8549.444286121383, 8553.95723021381, 8559.887214662054, 7358.705651773869, 7465.925340746523, 7586.203448637813, 7588.165301816685, 7612.048493938258, 7262.891056634049, 7335.4498412618195, 7343.2289609590225, 7454.329110380092, 7489.588072656056, 7026.156861176692, 7135.928640713734, 7175.461840362098, 7176.96411517939, 7180.425565954607, 4792.856214313576, 4934.481879402754, 4976.850694479172, 5081.415637201769, 5153.098207813692, 4927.97536012332, 5009.298698182184, 5046.823361182341, 5097.847064588068, 5266.78932842682], "value": 4563.702837230309, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.09297803923533, 315.70797012763194, 323.6084337951079, 329.64727741316784, 331.41670659639027, 5335.653182543137, 5805.390434004426, 5857.970710146448, 5871.972404829148, 5914.970425147868, 4777.52224777522, 5563.304586926634, 5566.165075238706, 5768.3654797972185, 5771.624844385112, 3951.308521008812, 4320.438343015404, 4361.760103194323, 4379.189849877783, 4396.274614688573, 3737.495438115847, 4111.075008123178, 4214.7471151731, 4328.545502722216, 4381.072328175608, 3582.5880023797336, 3846.2114966649237, 4321.6291292791675, 4321.675874309423, 4337.414449049385, 3202.647708752035, 3937.645611894929, 3942.250972315251, 4156.459660244179, 4272.807723652443, 3732.8133593320226, 3732.9813350933073, 3739.158689294914, 3744.326021316162, 3750.4374343848367, 4665.766711664403, 5319.308489896327, 5678.687672178028, 5718.48533631971, 5742.0, 4189.832406703728, 4357.128574285156, 4925.162722337212, 4997.550220480138, 5023.6985780853265, 6224.315325314212, 6225.595336303126, 6243.125962365227, 6244.968775156095, 6248.3751624837605, 3638.6361363863402, 3888.727789054767, 4135.462389889295, 4140.254754144253, 4161.604647558477, 5658.66048037115, 5699.74351154199, 6525.70634321452, 6587.7694280700425, 6589.967050164719, 4211.136716973013, 4214.789260536961, 4217.325227963511, 4225.640820530228, 4229.577042295746, 4013.2174226026227, 4233.767142807175, 4277.118068050048, 4281.681696685094, 4293.441852559147, 4983.835264606928, 4999.350084488993, 5013.222950442687, 5021.347224860818, 5029.5221953914315, 6249.218847644044, 6278.434940855345, 6280.717367718464, 6303.7793677222, 6316.242050953823, 1323.0936808286356, 1442.9927850360682, 1448.8406275309708, 1527.7020980908837, 1536.64657128861, 774.0440555913442, 901.0, 984.5766320482261, 992.4987881119988, 1023.4575674892319, 4589.908201835945, 4607.262837945913, 5782.855428614318, 5826.388229235954, 5853.326867410302, 5259.868503287448, 5821.505172060338, 6104.572679912452, 6241.96879015602, 6413.262474815363, 5563.248961390242, 5804.332501762351, 5808.664007278349, 5812.854678632983, 5853.239078919713, 3117.4544454720335, 3354.4129777288877, 3378.3412234614507, 3385.8476368563474, 3406.3357645259434, 5876.444750925714, 6414.550981431348, 6681.532292739462, 6695.15455931237, 6703.162104736908, 6493.448056915215, 6551.705173267214, 6574.638394888231, 6587.53887227899, 6615.536912416181, 6586.694656870057, 6666.566673166229, 6703.162104736908, 6706.329367063352, 6707.496937729592, 6148.907766383597, 6536.0, 6554.934450655433, 6576.638284894186, 6588.934110658832, 6352.618842869442, 6433.710483028181, 6446.774362897464, 6476.255230648536, 6492.740290388331, 5064.5441910228255, 6401.743930242738, 6648.434883034996, 6804.387605115563, 6831.385175334244, 3927.941080883732, 4004.579519150447, 4096.795160242035, 4124.340105583093, 4198.832046718097, 6488.156539649815, 6494.967525162533, 6504.15445992018, 6520.217573891164, 6520.543561950808, 5221.321228240305, 5746.2529871116485, 5894.0274854648505, 6277.5605707600025, 6288.402601752826, 5434.347878254634, 5710.0, 5800.1299805029585, 6238.469730072813, 6288.622682639057, 5087.720175390389, 6090.756369745161, 6146.692665366803, 6167.722452489559, 6171.352008039091, 5936.673482958306, 6199.380061993765, 6275.246970363585, 6308.400701933309, 6337.7781777637565, 5460.808871689631, 6204.13142160089, 6236.0, 6245.344238854855, 6266.561340706289], "value": 4492.278660514038, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.363622740283, 718.7412531488663, 741.7700512841017, 746.7162478258263, 752.9360004399625, 11855.407229638527, 12455.875441245586, 15426.305816238282, 15439.536813895602, 15639.843601563982, 14088.71822563548, 14172.71654566908, 14263.500777472791, 14270.785938210909, 14287.92856035721, 723.2803360656128, 740.3780824107748, 889.7820034091641, 908.1735620585268, 911.0752586125055, 888.0764005434373, 925.3060204846365, 928.0394791390912, 929.45162354211, 934.6448349627132, 980.6175591519313, 980.7106903463496, 983.4296108257194, 987.9950600246989, 989.9902099858141, 753.932146106853, 879.4371602174614, 932.3892850183141, 967.7129417874211, 980.3137803537517, 725.0031207090251, 726.3063774095726, 727.0620899040232, 728.035353157069, 728.5155371677828, 15393.153376564285, 15630.0, 15644.687106257925, 15666.608334791721, 15705.293261803246, 13019.934900325532, 13025.218486890817, 13060.346982650924, 13239.338033098307, 13409.932950335284, 9981.750456238653, 10250.436226007641, 10254.179665626732, 10580.418077005761, 10599.947000265027, 3517.6482351764876, 3543.3799085159994, 3579.194681196736, 3594.802285874276, 3687.520622319108, 91160.0, 91688.08311916862, 92236.53881730548, 95511.0, 98637.0, 8499.830003399897, 9338.719838404859, 9571.760705982268, 9918.206543476505, 10486.318389304672, 20893.477663058242, 21036.948152592307, 21051.78948210498, 21074.0, 21103.366898993056, 7426.7400640978385, 7625.199354067854, 7664.578448185292, 7772.300492955661, 7895.210478952059, 4076.7961601919787, 4083.3262511685834, 4096.73371230875, 4110.938335924963, 4123.484564429446, 3560.003199104255, 3637.94543081854, 3640.21735326906, 3640.508531348287, 3644.0, 602.9246344206974, 627.3757611176908, 630.2311180359953, 654.5908806995627, 689.0938415982966, 484.5881001149066, 527.7651445106967, 571.1546910572293, 578.6210032428766, 594.1029046140359, 7806.750919852796, 7977.800554986055, 8055.194480552015, 8204.630791614392, 8325.8334833303, 500.99050413416995, 515.487090345109, 553.2669213292404, 582.2925145947694, 583.5040215816522, 3881.650651441327, 3889.183271512957, 3907.433422153829, 4036.7577945323374, 4214.852480163179, 95167.52416237874, 95990.52004739932, 96289.0, 97762.0, 99531.0, 79447.60276198582, 84708.72936905951, 84828.0, 85606.0, 90208.5489572561, 55609.72195139079, 73032.90450643138, 74003.62998184975, 75057.0, 75266.24733752593, 7514.910338001068, 7647.48144008938, 8070.1122876484715, 8228.136045715113, 8264.83470330602, 42715.14569708526, 46043.33044197891, 46615.30077048779, 46875.53124468712, 47606.0, 62248.68875655593, 69316.0, 69632.0, 69651.30348696651, 69792.65103674449, 10984.450777461256, 11000.944995274973, 11119.332840029625, 11213.60752373663, 11229.157813163882, 11836.289822610672, 12063.879361206276, 12583.245005299712, 12938.223706577635, 12977.935110324388, 7873.645685944031, 11190.440477976232, 11305.830412543653, 11507.194496385173, 11766.352850593299, 11102.333859968428, 11305.943470282597, 11472.967432931076, 11594.0, 11620.302781833117, 9710.6115755369, 10893.509792059218, 11013.339199648046, 11299.0, 11500.53997840077, 9460.95269523648, 9478.715638530855, 9652.398735995494, 9868.802623947337, 10035.241983321097, 9546.522673866419, 9709.417434953926, 9714.659986900424, 9744.658936937456, 9792.902070979198], "value": 6933.108710443932, "unit": "score"}], "name": "dromaeo_css", "value": 7235.609561913304, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 112.0, 111.0, 114.0, 114.0, 119.0, 113.0, 114.0, 112.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 122.0, 143.0, 168.0, 119.0, 157.0, 158.0, 145.0, 182.0, 120.0], "value": 147.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 186.0, 186.0, 183.0, 186.0, 180.0, 181.0, 182.0, 185.0, 198.0], "value": 185.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 86.0, 91.0, 57.0, 55.0, 58.0, 77.0, 85.0, 56.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 133.0, 78.0, 78.0, 81.0, 122.0, 78.0, 78.0, 127.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 85.0, 83.0, 87.0, 84.0, 88.0, 83.0], "value": 85.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 93.0, 94.0, 93.0, 92.0, 93.0, 93.0, 93.0, 93.0, 92.0], "value": 97.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 83.0, 85.0, 86.0, 83.0, 84.0, 85.0, 86.0, 83.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 61.0, 59.0, 59.0, 62.0, 59.0, 60.0, 80.0, 61.0, 57.0], "value": 61.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 73.0, 76.0, 77.0, 72.0, 69.0, 69.0, 68.0, 69.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 90.0, 102.0, 83.0, 78.0, 79.0, 82.0, 84.0, 96.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 158.0, 159.0, 156.0, 138.0, 135.0, 134.0, 136.0, 171.0, 146.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 173.0, 171.0, 163.0, 170.0, 171.0, 173.0, 166.0, 169.0, 169.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 69.0, 68.0, 69.0, 66.0, 70.0, 70.0, 67.0, 66.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1483.5, "alertThreshold": 2.0}]} 05:07:33 INFO - Return code: 0 05:07:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:07:33 ERROR - # TBPL SUCCESS # 05:07:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:07:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:07:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:07:33 INFO - 2016-04-25 05:07:33.633 screenresolution[1674:9575] starting screenresolution argv=screenresolution get 05:07:33 INFO - 2016-04-25 05:07:33.636 screenresolution[1674:9575] Display 0: 1600x1200x32@60 05:07:33 INFO - 2016-04-25 05:07:33.641 screenresolution[1675:9579] starting screenresolution argv=screenresolution list 05:07:33 INFO - Available Modes on Display 0 05:07:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:07:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:07:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:07:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:07:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:07:33 INFO - Intel Iris: 05:07:33 INFO - Chipset Model: Intel Iris 05:07:33 INFO - Type: GPU 05:07:33 INFO - Bus: Built-In 05:07:33 INFO - VRAM (Dynamic, Max): 1536 MB 05:07:33 INFO - Vendor: Intel (0x8086) 05:07:33 INFO - Device ID: 0x0a2e 05:07:33 INFO - Revision ID: 0x0009 05:07:33 INFO - Displays: 05:07:33 INFO - Display: 05:07:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:07:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:07:33 INFO - Display Serial Number: 1600x1200 60 05:07:33 INFO - Main Display: Yes 05:07:33 INFO - Mirror: Off 05:07:33 INFO - Online: Yes 05:07:33 INFO - Rotation: Supported 05:07:33 INFO - Return code: 0 05:07:33 INFO - Running post-action listener: _resource_record_post_action 05:07:33 INFO - Running post-run listener: _resource_record_post_run 05:07:34 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 37099008; Write bytes: 693219840; Read time: 390; Write time: 1827 05:07:34 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 381198848; Write bytes: 371029504; Read time: 28087; Write time: 696 05:07:34 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30825984; Write bytes: 294759424; Read time: 308; Write time: 1072 05:07:34 INFO - Running post-run listener: _upload_blobber_files 05:07:34 INFO - Blob upload gear active. 05:07:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:07:34 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 05:07:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:07:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:07:35 INFO - (blobuploader) - INFO - Open directory for files ... 05:07:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:07:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:07:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:36 INFO - (blobuploader) - INFO - Done attempting. 05:07:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:07:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:07:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:37 INFO - (blobuploader) - INFO - Done attempting. 05:07:37 INFO - (blobuploader) - INFO - Iteration through files over. 05:07:37 INFO - Return code: 0 05:07:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:07:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:07:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5b54c404ee1231ebb17afd5a7a63374d17d5a4d64ac5cb15ce23a431d38da04a046c3e263af535b21ab63fcafd7a78510f382a6f6ec971926db86f3322855ddb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb1dfb43f570dcb671c6b6ffb866cb12be7085f782a438ab7f64fb67b3b7bf0a19e1d135cd03017d71b8a1f3231f0c41e47d7d4ed21f442f3201ac06881136db"} 05:07:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:07:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:07:37 INFO - Contents: 05:07:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5b54c404ee1231ebb17afd5a7a63374d17d5a4d64ac5cb15ce23a431d38da04a046c3e263af535b21ab63fcafd7a78510f382a6f6ec971926db86f3322855ddb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb1dfb43f570dcb671c6b6ffb866cb12be7085f782a438ab7f64fb67b3b7bf0a19e1d135cd03017d71b8a1f3231f0c41e47d7d4ed21f442f3201ac06881136db"} 05:07:37 INFO - Running post-run listener: copy_logs_to_upload_dir 05:07:37 INFO - Copying logs to upload dir... 05:07:37 INFO - mkdir: /builds/slave/test/build/upload/logs 05:07:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.579755 ========= master_lag: 4.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-04-25 05:07:41.900886) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-25 05:07:41.904708) ========= 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.b0uNuoBdc8/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.yxorJ7gQmK/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5b54c404ee1231ebb17afd5a7a63374d17d5a4d64ac5cb15ce23a431d38da04a046c3e263af535b21ab63fcafd7a78510f382a6f6ec971926db86f3322855ddb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb1dfb43f570dcb671c6b6ffb866cb12be7085f782a438ab7f64fb67b3b7bf0a19e1d135cd03017d71b8a1f3231f0c41e47d7d4ed21f442f3201ac06881136db"} build_url:https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011426 build_url: 'https://queue.taskcluster.net/v1/task/IxjqWs15RxqsGSSB6l6AJg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5b54c404ee1231ebb17afd5a7a63374d17d5a4d64ac5cb15ce23a431d38da04a046c3e263af535b21ab63fcafd7a78510f382a6f6ec971926db86f3322855ddb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb1dfb43f570dcb671c6b6ffb866cb12be7085f782a438ab7f64fb67b3b7bf0a19e1d135cd03017d71b8a1f3231f0c41e47d7d4ed21f442f3201ac06881136db"}' ========= master_lag: 1.19 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-25 05:07:43.109462) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:07:43.109752) ========= 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.b0uNuoBdc8/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.yxorJ7gQmK/Listeners TMPDIR=/var/folders/n0/6tpw2p6j3vx8fzyb0wrmlcwc00000w/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.005007 ========= master_lag: 0.67 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:07:43.787904) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 05:07:43.788180) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 05:07:44.601635) ========= ========= Total master_lag: 7.77 =========